builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1466526385.52 results: success (0) buildid: 20160621065001 builduid: 2fee93caf79d4667b704c6d65e49e84c revision: 5337f17b3c1fd9dc73f6e15956f71e8fb029e1ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:25.516284) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:25.516825) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:25.517128) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:25.548991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:25.549339) ========= 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=0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-21 09:26:25-- 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% 37.6M=0s 2016-06-21 09:26:25 (37.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080458 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:25.643887) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:25.644222) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017994 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:25.694385) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-21 09:26:25.694776) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5337f17b3c1fd9dc73f6e15956f71e8fb029e1ff --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5337f17b3c1fd9dc73f6e15956f71e8fb029e1ff --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=0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-21 09:26:25,760 truncating revision to first 12 chars 2016-06-21 09:26:25,760 Setting DEBUG logging. 2016-06-21 09:26:25,761 attempt 1/10 2016-06-21 09:26:25,761 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5337f17b3c1f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-21 09:26:26,949 unpacking tar archive at: mozilla-aurora-5337f17b3c1f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.531272 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-21 09:26:27.240123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:27.240433) ========= script_repo_revision: 5337f17b3c1fd9dc73f6e15956f71e8fb029e1ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:27.240872) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:27.241140) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 09:26:27.249559) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-21 09:26:27.249989) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:26:27 INFO - MultiFileLogger online at 20160621 09:26:27 in /builds/slave/test 09:26:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:26:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:27 INFO - {'append_to_log': False, 09:26:27 INFO - 'base_work_dir': '/builds/slave/test', 09:26:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:26:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:27 INFO - 'branch': 'Mozilla-Aurora', 09:26:27 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:27 INFO - 'config_files': ('talos/linux_config.py',), 09:26:27 INFO - 'default_actions': ('clobber', 09:26:27 INFO - 'read-buildbot-config', 09:26:27 INFO - 'download-and-extract', 09:26:27 INFO - 'populate-webroot', 09:26:27 INFO - 'create-virtualenv', 09:26:27 INFO - 'install', 09:26:27 INFO - 'run-tests'), 09:26:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:27 INFO - 'download_minidump_stackwalk': True, 09:26:27 INFO - 'download_symbols': 'ondemand', 09:26:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:27 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:27 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:27 INFO - 'installer_path': 'installer.exe', 09:26:27 INFO - 'log_level': 'info', 09:26:27 INFO - 'log_name': 'talos', 09:26:27 INFO - 'log_to_console': True, 09:26:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:26:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:26:27 INFO - 'opt_config_files': (), 09:26:27 INFO - 'pip_index': False, 09:26:27 INFO - 'sps_profile': False, 09:26:27 INFO - 'sps_profile_interval': 0, 09:26:27 INFO - 'suite': 'dromaeojs', 09:26:27 INFO - 'system_bits': '32', 09:26:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:27 INFO - 'title': 'talos-linux64-ix-017', 09:26:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:27 INFO - 'use_talos_json': True, 09:26:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:27 INFO - 'work_dir': 'build'} 09:26:27 INFO - ##### 09:26:27 INFO - ##### Running clobber step. 09:26:27 INFO - ##### 09:26:27 INFO - Running pre-action listener: _resource_record_pre_action 09:26:27 INFO - Running main action method: clobber 09:26:27 INFO - rmtree: /builds/slave/test/build 09:26:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:27 INFO - Running post-action listener: _resource_record_post_action 09:26:27 INFO - ##### 09:26:27 INFO - ##### Finished clobber step (success) 09:26:27 INFO - ##### 09:26:27 INFO - ##### 09:26:27 INFO - ##### Running read-buildbot-config step. 09:26:27 INFO - ##### 09:26:27 INFO - Running pre-action listener: _resource_record_pre_action 09:26:27 INFO - Running main action method: read_buildbot_config 09:26:27 INFO - Using buildbot properties: 09:26:27 INFO - { 09:26:27 INFO - "project": "", 09:26:27 INFO - "product": "firefox", 09:26:27 INFO - "script_repo_revision": "production", 09:26:27 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:26:27 INFO - "repository": "", 09:26:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:26:27 INFO - "buildid": "20160621065001", 09:26:27 INFO - "pgo_build": "True", 09:26:27 INFO - "basedir": "/builds/slave/test", 09:26:27 INFO - "buildnumber": 307, 09:26:27 INFO - "slavename": "talos-linux64-ix-017", 09:26:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:26:27 INFO - "platform": "ubuntu64_hw", 09:26:27 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:26:27 INFO - "branch": "mozilla-aurora", 09:26:27 INFO - "repo_path": "releases/mozilla-aurora", 09:26:27 INFO - "revision": "5337f17b3c1fd9dc73f6e15956f71e8fb029e1ff", 09:26:27 INFO - "stage_platform": "linux64-pgo", 09:26:27 INFO - "builduid": "2fee93caf79d4667b704c6d65e49e84c", 09:26:27 INFO - "slavebuilddir": "test" 09:26:27 INFO - } 09:26:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 09:26:27 INFO - Running post-action listener: _resource_record_post_action 09:26:27 INFO - ##### 09:26:27 INFO - ##### Finished read-buildbot-config step (success) 09:26:27 INFO - ##### 09:26:27 INFO - ##### 09:26:27 INFO - ##### Running download-and-extract step. 09:26:27 INFO - ##### 09:26:27 INFO - Running pre-action listener: _resource_record_pre_action 09:26:27 INFO - Running main action method: download_and_extract 09:26:27 INFO - mkdir: /builds/slave/test/build/tests 09:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:27 INFO - https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:26:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:26:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:26:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:26:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:26:28 INFO - Downloaded 1361 bytes. 09:26:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:26:28 INFO - Using the following test package requirements: 09:26:28 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:26:28 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:26:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:26:28 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:26:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:26:28 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:26:28 INFO - u'jsshell-linux-x86_64.zip'], 09:26:28 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:26:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:26:28 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:26:28 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:26:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:26:28 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:26:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:26:28 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:26:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:26:28 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:26:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:26:28 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:26:28 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')]} 09:26:28 INFO - https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:26:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:26:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:26:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:26:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:26:32 INFO - Downloaded 24474376 bytes. 09:26:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:26:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:26:33 INFO - Return code: 0 09:26:33 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:33 INFO - https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:26:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:26:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:26:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:26:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:26:35 INFO - Downloaded 24474376 bytes. 09:26:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:26:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:26:36 INFO - Return code: 0 09:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:36 INFO - https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:26:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:26:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:26:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:26:37 INFO - Downloaded 10833369 bytes. 09:26:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:26:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:26:37 INFO - Return code: 0 09:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:37 INFO - https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:26:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:26:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:26:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:26:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:26:45 INFO - Downloaded 63606549 bytes. 09:26:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:26:45 INFO - mkdir: /builds/slave/test/properties 09:26:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:45 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:45 INFO - Contents: 09:26:45 INFO - build_url:https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:26:46 INFO - Running post-action listener: _resource_record_post_action 09:26:46 INFO - Running post-action listener: set_extra_try_arguments 09:26:46 INFO - ##### 09:26:46 INFO - ##### Finished download-and-extract step (success) 09:26:46 INFO - ##### 09:26:46 INFO - ##### 09:26:46 INFO - ##### Running populate-webroot step. 09:26:46 INFO - ##### 09:26:46 INFO - Running pre-action listener: _resource_record_pre_action 09:26:46 INFO - Running main action method: populate_webroot 09:26:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:46 INFO - '--tppagecycles', 09:26:46 INFO - '7'], 09:26:46 INFO - 'tests': ['tsvgm']}}, 09:26:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:26:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:46 INFO - 'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:46 INFO - 'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['damp', 'tps']}, 09:26:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:46 INFO - 'tests': ['damp', 'tps']}, 09:26:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['dromaeo_dom']}, 09:26:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['a11yr', 09:26:46 INFO - 'ts_paint', 09:26:46 INFO - 'tpaint', 09:26:46 INFO - 'sessionrestore', 09:26:46 INFO - 'sessionrestore_no_auto_restore', 09:26:46 INFO - 'tabpaint']}, 09:26:46 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:46 INFO - 'ts_paint', 09:26:46 INFO - 'tpaint', 09:26:46 INFO - 'sessionrestore', 09:26:46 INFO - 'sessionrestore_no_auto_restore', 09:26:46 INFO - 'tabpaint']}, 09:26:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:46 INFO - 'ts_paint', 09:26:46 INFO - 'tpaint', 09:26:46 INFO - 'sessionrestore', 09:26:46 INFO - 'sessionrestore_no_auto_restore', 09:26:46 INFO - 'tabpaint']}, 09:26:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:46 INFO - 'ts_paint', 09:26:46 INFO - 'tpaint', 09:26:46 INFO - 'sessionrestore', 09:26:46 INFO - 'sessionrestore_no_auto_restore', 09:26:46 INFO - 'tabpaint']}, 09:26:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['a11yr', 09:26:46 INFO - 'ts_paint', 09:26:46 INFO - 'tpaint', 09:26:46 INFO - 'sessionrestore', 09:26:46 INFO - 'sessionrestore_no_auto_restore', 09:26:46 INFO - 'tabpaint']}, 09:26:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['a11yr', 09:26:46 INFO - 'ts_paint', 09:26:46 INFO - 'tpaint', 09:26:46 INFO - 'sessionrestore', 09:26:46 INFO - 'sessionrestore_no_auto_restore', 09:26:46 INFO - 'tabpaint']}, 09:26:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['tsvgx', 09:26:46 INFO - 'tsvgr_opacity', 09:26:46 INFO - 'tart', 09:26:46 INFO - 'tscrollx', 09:26:46 INFO - 'cart']}, 09:26:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:46 INFO - 'tsvgr_opacity', 09:26:46 INFO - 'tart', 09:26:46 INFO - 'tscrollx', 09:26:46 INFO - 'cart']}, 09:26:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:46 INFO - 'talos_options': ['--disable-e10s'], 09:26:46 INFO - 'tests': ['tp5o']}, 09:26:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:46 INFO - 'tests': ['tp5o']}, 09:26:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:46 INFO - 'talos_options': ['--disable-e10s', 09:26:46 INFO - '--xperf_path', 09:26:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:46 INFO - 'tests': ['tp5n']}, 09:26:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:26:46 INFO - 'talos_options': ['--xperf_path', 09:26:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:46 INFO - 'tests': ['tp5n']}}, 09:26:46 INFO - 'talos.zip': {'path': '', 09:26:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:46 INFO - Running post-action listener: _resource_record_post_action 09:26:46 INFO - ##### 09:26:46 INFO - ##### Finished populate-webroot step (success) 09:26:46 INFO - ##### 09:26:46 INFO - ##### 09:26:46 INFO - ##### Running create-virtualenv step. 09:26:46 INFO - ##### 09:26:46 INFO - Running pre-action listener: _resource_record_pre_action 09:26:46 INFO - Running main action method: create_virtualenv 09:26:46 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:46 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 09:26:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:46 INFO - Using real prefix '/usr' 09:26:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:46 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:48 INFO - Installing pip.................done. 09:26:48 INFO - Return code: 0 09:26:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26:48 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')]} 09:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 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')]} 09:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 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 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:26:48 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 09:26:48 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 09:26:48 INFO - Using env: {'DISPLAY': ':0', 09:26:48 INFO - 'HOME': '/home/cltbld', 09:26:48 INFO - 'LANG': 'en_US.UTF-8', 09:26:48 INFO - 'LANGUAGE': 'en_US:en', 09:26:48 INFO - 'LOGNAME': 'cltbld', 09:26:48 INFO - 'MAIL': '/var/mail/cltbld', 09:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:48 INFO - 'MOZ_NO_REMOTE': '1', 09:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:48 INFO - 'NO_EM_RESTART': '1', 09:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:48 INFO - 'PWD': '/builds/slave/test', 09:26:48 INFO - 'SHELL': '/bin/bash', 09:26:48 INFO - 'SHLVL': '1', 09:26:48 INFO - 'TERM': 'linux', 09:26:48 INFO - 'TMOUT': '86400', 09:26:48 INFO - 'USER': 'cltbld', 09:26:48 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:48 INFO - '_': '/tools/buildbot/bin/python'} 09:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:48 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 09:26:48 INFO - Cleaning up... 09:26:48 INFO - Return code: 0 09:26:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:26:48 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')]} 09:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 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')]} 09:26:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:26:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:26:48 INFO - Using env: {'DISPLAY': ':0', 09:26:48 INFO - 'HOME': '/home/cltbld', 09:26:48 INFO - 'LANG': 'en_US.UTF-8', 09:26:48 INFO - 'LANGUAGE': 'en_US:en', 09:26:48 INFO - 'LOGNAME': 'cltbld', 09:26:48 INFO - 'MAIL': '/var/mail/cltbld', 09:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:48 INFO - 'MOZ_NO_REMOTE': '1', 09:26:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:48 INFO - 'NO_EM_RESTART': '1', 09:26:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:48 INFO - 'PWD': '/builds/slave/test', 09:26:48 INFO - 'SHELL': '/bin/bash', 09:26:48 INFO - 'SHLVL': '1', 09:26:48 INFO - 'TERM': 'linux', 09:26:48 INFO - 'TMOUT': '86400', 09:26:48 INFO - 'USER': 'cltbld', 09:26:48 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:48 INFO - '_': '/tools/buildbot/bin/python'} 09:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:49 INFO - Downloading/unpacking psutil>=3.1.1 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:49 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 09:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:51 INFO - Installing collected packages: psutil 09:26:51 INFO - Running setup.py install for psutil 09:26:51 INFO - building 'psutil._psutil_linux' extension 09:26:51 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 09:26:51 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 09:26:51 INFO - building 'psutil._psutil_posix' extension 09:26:51 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 09:26:51 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 09:26:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:51 INFO - Successfully installed psutil 09:26:51 INFO - Cleaning up... 09:26:51 INFO - Return code: 0 09:26:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:26:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:26:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:26:51 INFO - Using env: {'DISPLAY': ':0', 09:26:51 INFO - 'HOME': '/home/cltbld', 09:26:51 INFO - 'LANG': 'en_US.UTF-8', 09:26:51 INFO - 'LANGUAGE': 'en_US:en', 09:26:51 INFO - 'LOGNAME': 'cltbld', 09:26:51 INFO - 'MAIL': '/var/mail/cltbld', 09:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:51 INFO - 'MOZ_NO_REMOTE': '1', 09:26:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:51 INFO - 'NO_EM_RESTART': '1', 09:26:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:51 INFO - 'PWD': '/builds/slave/test', 09:26:51 INFO - 'SHELL': '/bin/bash', 09:26:51 INFO - 'SHLVL': '1', 09:26:51 INFO - 'TERM': 'linux', 09:26:51 INFO - 'TMOUT': '86400', 09:26:51 INFO - 'USER': 'cltbld', 09:26:51 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:51 INFO - '_': '/tools/buildbot/bin/python'} 09:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:26:51 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 09:26:51 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 09:26:51 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 09:26:51 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 09:26:51 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 09:26:51 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 09:26:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:26:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:26:53 INFO - Installing collected packages: mozsystemmonitor 09:26:53 INFO - Running setup.py install for mozsystemmonitor 09:26:53 INFO - Successfully installed mozsystemmonitor 09:26:53 INFO - Cleaning up... 09:26:53 INFO - Return code: 0 09:26:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:26:53 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')]} 09:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:53 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')]} 09:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:26:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:26:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:26:53 INFO - Using env: {'DISPLAY': ':0', 09:26:53 INFO - 'HOME': '/home/cltbld', 09:26:53 INFO - 'LANG': 'en_US.UTF-8', 09:26:53 INFO - 'LANGUAGE': 'en_US:en', 09:26:53 INFO - 'LOGNAME': 'cltbld', 09:26:53 INFO - 'MAIL': '/var/mail/cltbld', 09:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:53 INFO - 'MOZ_NO_REMOTE': '1', 09:26:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:53 INFO - 'NO_EM_RESTART': '1', 09:26:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:53 INFO - 'PWD': '/builds/slave/test', 09:26:53 INFO - 'SHELL': '/bin/bash', 09:26:53 INFO - 'SHLVL': '1', 09:26:53 INFO - 'TERM': 'linux', 09:26:53 INFO - 'TMOUT': '86400', 09:26:53 INFO - 'USER': 'cltbld', 09:26:53 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:53 INFO - '_': '/tools/buildbot/bin/python'} 09:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:54 INFO - Downloading/unpacking jsonschema==2.5.1 09:26:54 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 09:26:54 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 09:26:54 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 09:26:54 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 09:26:54 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 09:26:54 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 09:26:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:26:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:56 INFO - Downloading functools32-3.2.3-2.tar.gz 09:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:26:56 INFO - warning: no files found matching '*.txt' 09:26:56 INFO - no previously-included directories found matching 'build' 09:26:56 INFO - no previously-included directories found matching 'dist' 09:26:56 INFO - no previously-included directories found matching '.git*' 09:26:56 INFO - Installing collected packages: jsonschema, functools32 09:26:56 INFO - Running setup.py install for functools32 09:26:56 INFO - warning: no files found matching '*.txt' 09:26:56 INFO - no previously-included directories found matching 'build' 09:26:56 INFO - no previously-included directories found matching 'dist' 09:26:56 INFO - no previously-included directories found matching '.git*' 09:26:56 INFO - Successfully installed jsonschema functools32 09:26:56 INFO - Cleaning up... 09:26:56 INFO - Return code: 0 09:26:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:26:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:26:56 INFO - Using env: {'DISPLAY': ':0', 09:26:56 INFO - 'HOME': '/home/cltbld', 09:26:56 INFO - 'LANG': 'en_US.UTF-8', 09:26:56 INFO - 'LANGUAGE': 'en_US:en', 09:26:56 INFO - 'LOGNAME': 'cltbld', 09:26:56 INFO - 'MAIL': '/var/mail/cltbld', 09:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:56 INFO - 'MOZ_NO_REMOTE': '1', 09:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:26:56 INFO - 'NO_EM_RESTART': '1', 09:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:56 INFO - 'PWD': '/builds/slave/test', 09:26:56 INFO - 'SHELL': '/bin/bash', 09:26:56 INFO - 'SHLVL': '1', 09:26:56 INFO - 'TERM': 'linux', 09:26:56 INFO - 'TMOUT': '86400', 09:26:56 INFO - 'USER': 'cltbld', 09:26:56 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:56 INFO - '_': '/tools/buildbot/bin/python'} 09:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:56 INFO - Downloading/unpacking blobuploader==1.2.4 09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:58 INFO - Downloading blobuploader-1.2.4.tar.gz 09:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:26:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:26:58 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 09:26:58 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 09:26:58 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 09:26:58 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 09:26:58 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 09:26:58 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 09:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:26:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:59 INFO - Downloading docopt-0.6.1.tar.gz 09:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:26:59 INFO - Installing collected packages: blobuploader, requests, docopt 09:26:59 INFO - Running setup.py install for blobuploader 09:26:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:26:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:26:59 INFO - Running setup.py install for requests 09:27:00 INFO - Running setup.py install for docopt 09:27:00 INFO - Successfully installed blobuploader requests docopt 09:27:00 INFO - Cleaning up... 09:27:00 INFO - Return code: 0 09:27:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:00 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 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:27:00 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 09:27:00 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 09:27:00 INFO - Using env: {'DISPLAY': ':0', 09:27:00 INFO - 'HOME': '/home/cltbld', 09:27:00 INFO - 'LANG': 'en_US.UTF-8', 09:27:00 INFO - 'LANGUAGE': 'en_US:en', 09:27:00 INFO - 'LOGNAME': 'cltbld', 09:27:00 INFO - 'MAIL': '/var/mail/cltbld', 09:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:00 INFO - 'MOZ_NO_REMOTE': '1', 09:27:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:00 INFO - 'NO_EM_RESTART': '1', 09:27:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:00 INFO - 'PWD': '/builds/slave/test', 09:27:00 INFO - 'SHELL': '/bin/bash', 09:27:00 INFO - 'SHLVL': '1', 09:27:00 INFO - 'TERM': 'linux', 09:27:00 INFO - 'TMOUT': '86400', 09:27:00 INFO - 'USER': 'cltbld', 09:27:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:00 INFO - '_': '/tools/buildbot/bin/python'} 09:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:00 INFO - Running setup.py (path:/tmp/pip-o0mR5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:00 INFO - Running setup.py (path:/tmp/pip-0FK8RG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:00 INFO - Running setup.py (path:/tmp/pip-wMIpHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:00 INFO - Running setup.py (path:/tmp/pip-udA2Zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:00 INFO - Running setup.py (path:/tmp/pip-RP6cbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:00 INFO - Running setup.py (path:/tmp/pip-Yiy5yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:00 INFO - Running setup.py (path:/tmp/pip-_O2VLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:00 INFO - Running setup.py (path:/tmp/pip-c7n6OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:00 INFO - Running setup.py (path:/tmp/pip-JYu2Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:01 INFO - Running setup.py (path:/tmp/pip-6Q347p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:01 INFO - Running setup.py (path:/tmp/pip-eAA3z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:01 INFO - Running setup.py (path:/tmp/pip-V8XyrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:01 INFO - Running setup.py (path:/tmp/pip-2AEf6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:01 INFO - Running setup.py (path:/tmp/pip-89L4d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:01 INFO - Running setup.py (path:/tmp/pip-_rR59V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:01 INFO - Running setup.py (path:/tmp/pip-I1438g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:01 INFO - Running setup.py (path:/tmp/pip-32zcKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:01 INFO - Running setup.py install for manifestparser 09:27:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:01 INFO - Running setup.py install for mozcrash 09:27:01 INFO - Running setup.py install for mozdebug 09:27:01 INFO - Running setup.py install for mozdevice 09:27:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Running setup.py install for mozfile 09:27:02 INFO - Running setup.py install for mozhttpd 09:27:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Running setup.py install for mozinfo 09:27:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Running setup.py install for mozInstall 09:27:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Running setup.py install for mozleak 09:27:02 INFO - Running setup.py install for mozlog 09:27:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Running setup.py install for moznetwork 09:27:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:02 INFO - Running setup.py install for mozprocess 09:27:02 INFO - Running setup.py install for mozprofile 09:27:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:03 INFO - Running setup.py install for mozrunner 09:27:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:03 INFO - Running setup.py install for mozscreenshot 09:27:03 INFO - Running setup.py install for moztest 09:27:03 INFO - Running setup.py install for mozversion 09:27:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:03 INFO - Cleaning up... 09:27:03 INFO - Return code: 0 09:27:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:03 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 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:27:03 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 09:27:03 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 09:27:03 INFO - Using env: {'DISPLAY': ':0', 09:27:03 INFO - 'HOME': '/home/cltbld', 09:27:03 INFO - 'LANG': 'en_US.UTF-8', 09:27:03 INFO - 'LANGUAGE': 'en_US:en', 09:27:03 INFO - 'LOGNAME': 'cltbld', 09:27:03 INFO - 'MAIL': '/var/mail/cltbld', 09:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:03 INFO - 'MOZ_NO_REMOTE': '1', 09:27:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:03 INFO - 'NO_EM_RESTART': '1', 09:27:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:03 INFO - 'PWD': '/builds/slave/test', 09:27:03 INFO - 'SHELL': '/bin/bash', 09:27:03 INFO - 'SHLVL': '1', 09:27:03 INFO - 'TERM': 'linux', 09:27:03 INFO - 'TMOUT': '86400', 09:27:03 INFO - 'USER': 'cltbld', 09:27:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:03 INFO - '_': '/tools/buildbot/bin/python'} 09:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:03 INFO - Running setup.py (path:/tmp/pip-df5b_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:03 INFO - Running setup.py (path:/tmp/pip-_uquhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:03 INFO - Running setup.py (path:/tmp/pip-6wEyp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:03 INFO - Running setup.py (path:/tmp/pip-cmsoYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:03 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)) 09:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:04 INFO - Running setup.py (path:/tmp/pip-2Ki04F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:04 INFO - Running setup.py (path:/tmp/pip-a_mUnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:04 INFO - Running setup.py (path:/tmp/pip-siVwPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:04 INFO - Running setup.py (path:/tmp/pip-aqHWm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:04 INFO - Running setup.py (path:/tmp/pip-tH3ZIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:04 INFO - Running setup.py (path:/tmp/pip-UTmVUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:04 INFO - Running setup.py (path:/tmp/pip-BLcWAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:04 INFO - Running setup.py (path:/tmp/pip-jbg7O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:04 INFO - Running setup.py (path:/tmp/pip-TaC_Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:04 INFO - Running setup.py (path:/tmp/pip-f062CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:04 INFO - Running setup.py (path:/tmp/pip-X69amI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:04 INFO - Running setup.py (path:/tmp/pip-drDGqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:04 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)) 09:27:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:04 INFO - Running setup.py (path:/tmp/pip-da2QTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:04 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)) 09:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:27:04 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)) 09:27:04 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)) 09:27:04 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)) 09:27:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:06 INFO - Downloading blessings-1.6.tar.gz 09:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:06 INFO - Installing collected packages: blessings 09:27:06 INFO - Running setup.py install for blessings 09:27:07 INFO - Successfully installed blessings 09:27:07 INFO - Cleaning up... 09:27:07 INFO - Return code: 0 09:27:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:07 INFO - Reading from file tmpfile_stdout 09:27:07 INFO - Current package versions: 09:27:07 INFO - argparse == 1.2.1 09:27:07 INFO - blessings == 1.6 09:27:07 INFO - blobuploader == 1.2.4 09:27:07 INFO - docopt == 0.6.1 09:27:07 INFO - functools32 == 3.2.3-2 09:27:07 INFO - jsonschema == 2.5.1 09:27:07 INFO - manifestparser == 1.1 09:27:07 INFO - mozInstall == 1.12 09:27:07 INFO - mozcrash == 0.17 09:27:07 INFO - mozdebug == 0.1 09:27:07 INFO - mozdevice == 0.48 09:27:07 INFO - mozfile == 1.2 09:27:07 INFO - mozhttpd == 0.7 09:27:07 INFO - mozinfo == 0.9 09:27:07 INFO - mozleak == 0.1 09:27:07 INFO - mozlog == 3.2 09:27:07 INFO - moznetwork == 0.27 09:27:07 INFO - mozprocess == 0.22 09:27:07 INFO - mozprofile == 0.28 09:27:07 INFO - mozrunner == 6.11 09:27:07 INFO - mozscreenshot == 0.1 09:27:07 INFO - mozsystemmonitor == 0.3 09:27:07 INFO - moztest == 0.7 09:27:07 INFO - mozversion == 1.4 09:27:07 INFO - psutil == 3.1.1 09:27:07 INFO - requests == 1.2.3 09:27:07 INFO - wsgiref == 0.1.2 09:27:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27:07 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 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:27:07 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 09:27:07 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 09:27:07 INFO - Using env: {'DISPLAY': ':0', 09:27:07 INFO - 'HOME': '/home/cltbld', 09:27:07 INFO - 'LANG': 'en_US.UTF-8', 09:27:07 INFO - 'LANGUAGE': 'en_US:en', 09:27:07 INFO - 'LOGNAME': 'cltbld', 09:27:07 INFO - 'MAIL': '/var/mail/cltbld', 09:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:07 INFO - 'MOZ_NO_REMOTE': '1', 09:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:07 INFO - 'NO_EM_RESTART': '1', 09:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:07 INFO - 'PWD': '/builds/slave/test', 09:27:07 INFO - 'SHELL': '/bin/bash', 09:27:07 INFO - 'SHLVL': '1', 09:27:07 INFO - 'TERM': 'linux', 09:27:07 INFO - 'TMOUT': '86400', 09:27:07 INFO - 'USER': 'cltbld', 09:27:07 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:07 INFO - '_': '/tools/buildbot/bin/python'} 09:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 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)) 09:27:07 INFO - Cleaning up... 09:27:07 INFO - Return code: 0 09:27:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:27: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', 'jsonschema'] in /builds/slave/test/build 09:27: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 jsonschema 09:27:07 INFO - Using env: {'DISPLAY': ':0', 09:27:07 INFO - 'HOME': '/home/cltbld', 09:27:07 INFO - 'LANG': 'en_US.UTF-8', 09:27:07 INFO - 'LANGUAGE': 'en_US:en', 09:27:07 INFO - 'LOGNAME': 'cltbld', 09:27:07 INFO - 'MAIL': '/var/mail/cltbld', 09:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:07 INFO - 'MOZ_NO_REMOTE': '1', 09:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:07 INFO - 'NO_EM_RESTART': '1', 09:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:07 INFO - 'PWD': '/builds/slave/test', 09:27:07 INFO - 'SHELL': '/bin/bash', 09:27:07 INFO - 'SHLVL': '1', 09:27:07 INFO - 'TERM': 'linux', 09:27:07 INFO - 'TMOUT': '86400', 09:27:07 INFO - 'USER': 'cltbld', 09:27:07 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:07 INFO - '_': '/tools/buildbot/bin/python'} 09:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:07 INFO - Cleaning up... 09:27:07 INFO - Return code: 0 09:27:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:27: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')]} 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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')]} 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:27: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d9c502f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16748a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x173f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, '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': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', '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 09:27: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', 'flake8'] in /builds/slave/test/build 09:27: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 flake8 09:27:07 INFO - Using env: {'DISPLAY': ':0', 09:27:07 INFO - 'HOME': '/home/cltbld', 09:27:07 INFO - 'LANG': 'en_US.UTF-8', 09:27:07 INFO - 'LANGUAGE': 'en_US:en', 09:27:07 INFO - 'LOGNAME': 'cltbld', 09:27:07 INFO - 'MAIL': '/var/mail/cltbld', 09:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:07 INFO - 'MOZ_NO_REMOTE': '1', 09:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:07 INFO - 'NO_EM_RESTART': '1', 09:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:07 INFO - 'PWD': '/builds/slave/test', 09:27:07 INFO - 'SHELL': '/bin/bash', 09:27:07 INFO - 'SHLVL': '1', 09:27:07 INFO - 'TERM': 'linux', 09:27:07 INFO - 'TMOUT': '86400', 09:27:07 INFO - 'USER': 'cltbld', 09:27:07 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:07 INFO - '_': '/tools/buildbot/bin/python'} 09:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:07 INFO - Downloading/unpacking flake8 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:27:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27: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 09:27:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:27:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:27:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:27:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:27:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:27:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:27:10 INFO - no previously-included directories found matching 'docs/_build' 09:27:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:27:10 INFO - Running setup.py install for pep8 09:27:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:27:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:27:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:27:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:27:10 INFO - no previously-included directories found matching 'docs/_build' 09:27:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:27:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:27:10 INFO - Cleaning up... 09:27:11 INFO - Return code: 0 09:27:11 INFO - Running post-action listener: _resource_record_post_action 09:27:11 INFO - Running post-action listener: _start_resource_monitoring 09:27:11 INFO - Starting resource monitoring. 09:27:11 INFO - ##### 09:27:11 INFO - ##### Finished create-virtualenv step (success) 09:27:11 INFO - ##### 09:27:11 INFO - ##### 09:27:11 INFO - ##### Running install step. 09:27:11 INFO - ##### 09:27:11 INFO - Running pre-action listener: _resource_record_pre_action 09:27:11 INFO - Running main action method: install 09:27:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:11 INFO - Reading from file tmpfile_stdout 09:27:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:11 INFO - Reading from file tmpfile_stdout 09:27:11 INFO - Output received: 09:27:11 INFO - Usage: mozinstall [options] installer 09:27:11 INFO - Options: 09:27:11 INFO - -h, --help show this help message and exit 09:27:11 INFO - -d DEST, --destination=DEST 09:27:11 INFO - Directory to install application into. [default: 09:27:11 INFO - "/builds/slave/test"] 09:27:11 INFO - --app=APP Application being installed. [default: firefox] 09:27:11 INFO - mkdir: /builds/slave/test/build/application 09:27:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:27:27 INFO - Reading from file tmpfile_stdout 09:27:27 INFO - Output received: 09:27:27 INFO - /builds/slave/test/build/application/firefox/firefox 09:27:27 INFO - Running post-action listener: _resource_record_post_action 09:27:27 INFO - ##### 09:27:27 INFO - ##### Finished install step (success) 09:27:27 INFO - ##### 09:27:27 INFO - ##### 09:27:27 INFO - ##### Running run-tests step. 09:27:27 INFO - ##### 09:27:27 INFO - Running pre-action listener: _resource_record_pre_action 09:27:27 INFO - Running main action method: run_tests 09:27:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:27:27 INFO - Python 2.7.3 09:27:27 INFO - Return code: 0 09:27:27 INFO - grabbing minidump binary from tooltool 09:27:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ce1c0>, '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 0x17cf0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17cf550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:27:27 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 09:27:27 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 09:27:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:27:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:27:27 INFO - Return code: 0 09:27:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:27:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:27:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:27:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:27:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:27:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:27:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:27:28 INFO - Return code: 0 09:27:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 09:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 09:27:28 INFO - Using env: {'DISPLAY': ':0', 09:27:28 INFO - 'HOME': '/home/cltbld', 09:27:28 INFO - 'LANG': 'en_US.UTF-8', 09:27:28 INFO - 'LANGUAGE': 'en_US:en', 09:27:28 INFO - 'LOGNAME': 'cltbld', 09:27:28 INFO - 'MAIL': '/var/mail/cltbld', 09:27:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:27:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:28 INFO - 'MOZ_NO_REMOTE': '1', 09:27:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:27:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:27:28 INFO - 'NO_EM_RESTART': '1', 09:27:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:28 INFO - 'PWD': '/builds/slave/test', 09:27:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:27:28 INFO - 'SHELL': '/bin/bash', 09:27:28 INFO - 'SHLVL': '1', 09:27:28 INFO - 'TERM': 'linux', 09:27:28 INFO - 'TMOUT': '86400', 09:27:28 INFO - 'USER': 'cltbld', 09:27:28 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900', 09:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:28 INFO - '_': '/tools/buildbot/bin/python'} 09:27:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 09:27:28 INFO - mozversion application_buildid: 20160621065001 09:27:28 INFO - mozversion application_changeset: 5337f17b3c1fd9dc73f6e15956f71e8fb029e1ff 09:27:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:27:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:27:28 INFO - mozversion application_name: Firefox 09:27:28 INFO - mozversion application_remotingname: firefox-dev 09:27:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:27:28 INFO - mozversion application_vendor: Mozilla 09:27:28 INFO - mozversion application_version: 49.0a2 09:27:28 INFO - mozversion platform_buildid: 20160621065001 09:27:28 INFO - mozversion platform_changeset: 5337f17b3c1fd9dc73f6e15956f71e8fb029e1ff 09:27:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:27:28 INFO - mozversion platform_version: 49.0a2 09:27:28 INFO - using testdate: 1466526448 09:27:28 INFO - actual date: 1466526448 09:27:28 INFO - starting webserver on 'localhost:39818' 09:27:28 INFO - SUITE-START | Running 2 tests 09:27:28 INFO - TEST-START | dromaeo_css 09:27:28 INFO - operating with platform_type : linux_ 09:27:28 INFO - Initialising browser for dromaeo_css test... 09:27:28 INFO - TEST-INFO | started process 20522 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptN882t/profile http://localhost:39818/getInfo.html) 09:27:32 INFO - PROCESS | 20522 | __metrics Screen width/height:1600/1200 09:27:32 INFO - PROCESS | 20522 | colorDepth:24 09:27:32 INFO - PROCESS | 20522 | Browser inner width/height: 1024/697 09:27:32 INFO - PROCESS | 20522 | __metrics 09:27:33 ERROR - PROCESS | 20522 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:27:33 INFO - PROCESS | 20522 | console.error: PushService: 09:27:33 INFO - PROCESS | 20522 | stateChangeProcessEnqueue: Error transitioning state 09:27:33 INFO - PROCESS | 20522 | UnknownError 09:27:34 INFO - TEST-INFO | 20522: exit 0 09:27:34 INFO - Browser initialized. 09:27:34 INFO - Running cycle 1/1 for dromaeo_css test... 09:27:34 INFO - TEST-INFO | started process 20614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptN882t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:46 INFO - PROCESS | 20614 | RSS: Main: 145444864 09:27:46 INFO - PROCESS | 20614 | 09:28:37 INFO - PROCESS | 20614 | 1466526517350 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] 09:30:45 INFO - PROCESS | 20614 | Cycle 1(1): loaded http://localhost:39818/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39818/tests/dromaeo/cssquery-ext.html) 09:30:46 INFO - PROCESS | 20614 | RSS: Main: 256507904 09:30:46 INFO - PROCESS | 20614 | 09:33:48 INFO - PROCESS | 20614 | Cycle 1(1): loaded http://localhost:39818/tests/dromaeo/cssquery-ext.html (next: http://localhost:39818/tests/dromaeo/cssquery-jquery.html) 09:33:49 INFO - PROCESS | 20614 | RSS: Main: 229400576 09:33:49 INFO - PROCESS | 20614 | 09:36:51 INFO - PROCESS | 20614 | Cycle 1(1): loaded http://localhost:39818/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39818/tests/dromaeo/cssquery-mootools.html) 09:36:51 INFO - PROCESS | 20614 | RSS: Main: 232570880 09:36:51 INFO - PROCESS | 20614 | 09:39:53 INFO - PROCESS | 20614 | Cycle 1(1): loaded http://localhost:39818/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39818/tests/dromaeo/cssquery-prototype.html) 09:39:54 INFO - PROCESS | 20614 | RSS: Main: 227180544 09:39:54 INFO - PROCESS | 20614 | 09:42:56 INFO - PROCESS | 20614 | Cycle 1(1): loaded http://localhost:39818/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39818/tests/dromaeo/cssquery-yui.html) 09:42:57 INFO - PROCESS | 20614 | RSS: Main: 216633344 09:42:57 INFO - PROCESS | 20614 | 09:45:59 INFO - PROCESS | 20614 | Cycle 1(1): loaded http://localhost:39818/tests/dromaeo/cssquery-yui.html (next: http://localhost:39818/tests/dromaeo/cssquery-dojo.html) 09:46:00 INFO - PROCESS | 20614 | RSS: Main: 261255168 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - PROCESS | 20614 | __start_tp_report 09:46:00 INFO - PROCESS | 20614 | _x_x_mozilla_page_load 09:46:00 INFO - PROCESS | 20614 | _x_x_mozilla_page_load_details 09:46:00 INFO - PROCESS | 20614 | |i|pagename|runs| 09:46:00 INFO - PROCESS | 20614 | |0;dojo.html;3418.2308980479397;3436.948445773314;3437.0719905625488;3457.4468085106387;3495.143689796;5665.093585026397;5741.0240259155935;5776;5792.76828926843;5795.623284486505;5486.012517746804;5520.392756796749;5522.585806064541;5525.364583072947;5534.252875861757;4894.4371397289415;4981.576565991895;4983.152864013126;4987.102321582114;5019.824306149286;5035.546800787928;5079.060373830853;5083.313752643401;5084.491550844923;5085.211792172201;5051.722155281458;5082.923756143661;5105.974470127663;5110.156824124015;5148.768305426265;5187.533122019036;5213.3813114803315;5214.426413094557;5217.843464696065;5244.187150991603;4902.093112774148;4944.085344211332;4978.950210497885;5118.155504341779;5128.051310507568;5479.068558345091;5492.670439773627;5496.0931446311315;5516.862078448031;5522.66863988162;6088.645418326693;6088.817335479943;6098.08528720691;6113.969430152865;6123.663198524079;5951.761929522814;6031.849203769897;6040.335563088057;6078.361772013919;6118.632882027093;5666.150077488369;5683.403242659544;5704.828855134311;5710.2862142232225;5718.542516598662;5948.702564871825;6204.100405441276;6204.13142160098;6219.502439804804;6222.906656400158;5850.210221620112;5920.555958303058;5924.4667979882015;6026.849328766816;6048.546359023091;6076.848078797977;6082.255840444372;6083.878322433527;6092.786752463642;6115.113308570232;5785.797497087666;5973.133895585116;6058.606190597597;6063.272407311151;6067.24159480065;5991.191189189403;6083.178770865877;6087.69561521931;6093.482054025369;6129.233845769279;4622.745748983838;4638.768061596907;4640.164770341371;4643.6517261205545;4667;4025.9597404025585;4066.471358723347;4077.7961101944784;4150.91698166041;4160.375943608423;5741.052726300197;5760.308762948473;5768.192453056576;5769.221155144001;5782.855428614234;6025.8192254232445;6078.665673388006;6079;6080.0879868019265;6103.511719062464;5532.723363831792;5589.552835773128;5646.322441307069;5646.378898321182;5752.597318187687;5046.066477701637;5059.924101138486;5060.418051924003;5062.594992400599;5087.211482220262;6205.162303089115;6240.843978900473;6244.937550624527;6246.406591373812;6254.562180647311;5794.449527294901;5961.2846458425265;6025.758969641165;6079.05774604937;6084.148219249397;6288.213973253343;6340;6353.174087368612;6362.013887847391;6364.363563643598;5966.463018328372;5973.790917317873;5977.103434484862;5996.4603185713495;6004.729787159676;5850.327212370462;5932.16949627044;5951.791687291097;5961.910571341346;6034.698265086816;5837.153612726216;5867.178594996789;5867.970660146672;5869.4717475427415;5871.647701137775;5696.458836410535;5738.368779434176;5807.680577568274;5811.82564523065;5857.326407463196;5818.4763371296785;5879.676617785892;5899.616524925866;5942.494887934574;5943.821685349447;6052.061930400795;6100.389961004042;6102.115193297036;6102.4507794298725;6103.938960610337;5603.439656034364;5648.435156484319;5928.318243402064;5937.317208521075;5937.495312898452 09:46:00 INFO - PROCESS | 20614 | |1;ext.html;588.5174157191102;639.6258188959458;646.7671638210244;658.6969993802851;670.3966430212805;32155.51766723498;34899.82550087249;46963.29555056677;47600.285995710095;47651.76174119124;47608.28587571181;47833.521664783344;47920;47982.76008619961;48119.75940120303;8738.300935925143;9098.772530686736;9359.20446762026;9366.438013719164;11071.335719856794;10789.568417263301;11246.718832029183;11252.66242012741;11256.606018789344;11257.155713321492;11279.32324060559;11328.603498877541;11329.546818127264;11334.943325283362;11340.432978351091;8593.828123437528;11048.613298534594;11175.608853690122;11344.659660210207;11419.600313989054;10820.891791082067;11235.550577976872;11243.60647377338;11249.943750281278;11250.49372778227;46211.53788462139;46680.299795503095;46692;46798;46811.531884681055;3830.616938306175;3915.295246855565;3970.4441378206784;4224.45082139323;4460.442444694413;34979.82510087459;34998.65001349979;35113.82443087794;35181;35377.82311088428;4433.866983990485;4452.332150177499;4518.118966801475;4583.518730533279;4648.604868586174;376544;380506;382200;382252;383343;40266.59733402687;72408;73099.26900730925;73288.63355683188;73429;40600;42383.36424953566;42444;42450.575494244666;42668.57331426646;15776.526704198892;15958.521244362688;16022.278997445143;16060.75908861368;16076.196190190678;11855.70360740971;12808;13081.345932703518;13092.476300947856;13332.133411328232;9433.433993960385;9444.433333999981;9457.19613832832;9507.809843803085;9510.144087032062;3520.437708420689;3617.6382361763613;3622.1125824173246;3632.2372301816386;3674.2835147146584;1100.8954149355798;1112.260346869339;1184.1178322150054;1193.8837187229967;1227.613301809927;33948;34200.14499637529;34345.82827085898;34519.137021574665;34878.302433951176;1160.7504386556932;1209.4013463335705;1240.0053869904082;1269.7587458382877;1275.9298238596966;17830.286788528574;17890.821091789177;18029;18061.548461288574;18095.823771454812;575859;576613;577112;579261;581661;61294.69352653208;62802;62925;63055.6847215761;63104.36895630985;1375.4910683047137;1460.9269536523132;1475.1517877220426;1488.3079368093888;1512.4177191781023;32614.51078233781;33936;34049.14877128092;34695.65304347025;35294.47058294179;52906.206406903155;53408.198877017654;53425.931481370935;53718.73140634272;54016.45983540272;422978;424151;426246;426801;426873;28113.578296325162;30578;31457.21356966094;31490.370192596478;32741.672583274816;50230;51823;52468.47531524636;53309;54072;43730.78134609306;44105.77947110244;44831;44900.11616138918;45627;43646.74628199767;44621.107577847615;44726.77636611796;44862.3270650934;46194.76902615465;44425.77787111044;45357.09285814331;45513.089738205716;45665.77167114143;45763;36399.636003640684;39612.40581391339;39879.20241595094;40141;40187.79906100451;29355.266118346357;29598.55602165926;29672.703272967858;29972.550411744276;30060.84969575138 09:46:00 INFO - PROCESS | 20614 | |2;jquery.html;424.4397395438021;427.1137389915925;434.1057421711275;435.37958409266787;451.1225666079475;18420.355287564937;19519.512012199684;20533.589328213464;20551.177952881866;20575.176992920286;19799.307024254154;19809.405717828453;19881.50296242591;20433.18267269311;20450.693239601416;5791.391903850098;5993.9400605993815;6015.398460153993;6015.849603759897;6016.909746353808;5585;5752.654840709571;5756.3092428908585;5871.119332100197;5915.852103697399;5701.344345400291;5703.800366987156;5706.7431965561445;5724.370319264898;5727.799527016556;5656.377798442168;5718.571107166958;5815.38938411467;5990.311114221833;5998.8500287492725;5365.892682146336;5379.1393377059485;5390.460953904618;5390.56875449967;5404.864878378072;23016;24134.758652413428;24578;24582;24653.260402187785;2489.593459458116;2527.734587868266;2541.3265484646586;2578.1234380310784;2816.901408450715;10095.192384609214;10225.693229203136;10811;10940.4529773511;15303.846961530353;1899.6105798311312;1963.1754663041556;1998.3305592626486;2023.9190432382834;2058.8661736987046;58468;83024;84178;85199;85885.57057214674;11613.245139065933;11810.232334898204;11908.880911190778;11920.582779602672;12775.61673149807;16625.251863666163;16702.91648541774;16722.414715484898;16743.16284185786;16762.74855877163;7828.138904720477;8013.759587212393;8072.677092916335;8148.307393871587;8149.755507334789;7702.3453006493955;7775.611219439005;7775.766726998199;7831.099423566363;7933.801654958556;5026.924596131061;5157.535821775983;5158.535731784157;5165.741712914414;5213.035588416155;2136.134865379524;2144.0780464400145;2153.7307836520436;2169.1323470611883;2203.559288142378;963.4556475591269;1017.6896046705825;1036.9792604147874;1080.287010573018;1133.2350663302402;19451.90274048649;19787.901060494605;20107.698384523952;20375.388738337875;20572.485687857923;1022.5143057047992;1046.858674079005;1063.2079101069753;1085.7882712871071;1092.639428988432;9582.808343833085;11381.317120972768;11400.771984560428;11481.770364592661;11486.59796907121;527066;529512;545417;549085;550275;97351.51324243475;100936;101764.49117754512;101831;101937.49031254945;1101.493313075994;1141.058626633031;1157.9710507237387;1159.837622732818;1163.040491594438;17874.821251787314;18331.266749329876;18454.907725461286;18465.446036618923;18567.628647427246;41117.79441102775;42424.36363454489;43339.78330108329;43858.122837543706;43911.12177756363;59796.40203597908;59814;60733;63643.36356636375;64023;7375.151857536237;11618.76762464763;12119.636410907688;12557.309347985949;13250.867491324963;13368;14568.27158642085;14582.708345832812;14722.852771472148;15114.395424183349;12536.310502922426;12597.87402125967;12738.617841464771;12827.743445131231;12922.935385323013;12446.190997585129;12636.810447843105;12696.936515317364;12853.97168226521;12864.678383040498;12765.680857978254;12922.289274090015;12954.611361659165;13077.280749558866;13349.199048057148;13400.195988240346;13419.463221471422;13447;13469.057165998678;13535.526256580972;12317.876821232032;12496.187747796728;12506.499740010298;12564.37178141071;12659.87340126587 09:46:00 INFO - PROCESS | 20614 | |3;mootools.html;1418.2270662488943;1606.750953602192;1645.5227983884674;1851.5093500222442;1964.0474369930582;9131.223845973092;9403.764905877357;9429.057094290567;9432.056794320573;9698.70903872885;9085.772855678595;9404.341696081277;9714.222862171027;9728.902710972889;9729.513524323791;2194.517206214638;2252.2117258959397;2450.950980980384;2501.6747822783013;2505.6366826810104;2517.471331020488;2537.3783423061377;2542.2373288013523;2543.0209369392733;2543.529447052292;2443.4746529496156;2479.566075936713;2483.3295010347197;2483.677121974141;2485.142625794107;2135.370065830584;2223.5664045511053;2278.0773786616282;2283.7373702024147;2369.455025344172;2359.740428552858;2368.7157541095007;2378.988105059481;2379.5240951809706;2384.3800611840875;9658;9674.9516252419;9710.368826026286;9715.854262186072;9718.173955213815;5581.6371935824045;5790.826275211751;5797.072468405035;5797.710114494258;5801;3500.5449291592186;3564.6613571710905;3618.185908170667;3633.309671162497;3639.5996440391536;1160.4371879638368;1176.288345550938;1180.9704757381048;1231.279701374697;1239.9814002789965;13430.261335626537;13552.93223533876;13600.183988960694;13601.251931143783;13623;4958.727270000112;5037.571806396498;5043.445221025684;5079.898402032012;5101.617378696613;11276.210665253353;11344.262622929484;11345.489452974636;11355.772884542428;11358.26171298863;5697.914531282034;5702.315722113373;5710.543156547466;5710.685912274865;5713.514351280188;4562.201614717411;5283.339582552181;5384.273123128406;5388.326459192601;5516.586256030814;4441.267190913528;4467.329900514948;4539.228331183706;4543.568361005699;4565.817367305337;1391.589481103077;1420.1053336398004;1424.5655075201955;1427.108057464085;1442.740306744796;1033.1476531861244;1044.1072882685346;1047.2459828923163;1052.221356196409;1053.2890299048113;6250.9062364064575;6402.9359706403275;6484.481241500669;6518.054882042077;6581.572197807127;501.7115158783714;505.39605171820057;506.7972810875679;511.6597462687346;519.9532042116227;4425.955740442554;4498.617617502549;4599.609033232213;4774.331593576902;4801;12111.333876636694;12163.574274900513;12275.69310767238;12279.324637145042;12458.688532786571;11809.878061584135;12160.08799340053;12180.269183848997;12228.571999979958;12240.571579994657;8816;9280.582373793195;9319.534023298807;9363.578638961128;9415.05849415053;6529.804105876831;6666.300038495888;6821.727130914906;6825.283345248876;6885.51801373899;11298.265612735146;11310.4910279036;11338.527619618353;11540.596079137189;11635.650930472098;11592.536298547964;11638.127140464669;11778.941105294762;11780.646580602595;11842.230255033395;7676.769696909102;8139.267465928095;8243.752687419386;8354.832903342021;8547.145285471652;9539.856902146335;9568.186704130227;9612.855807163038;9641.27690423207;9668.323217374998;8487.066422693622;8652.22130008277;8657.091005444337;8663.870041949249;8815.074417186359;8297.83404331922;8417.410781245255;8448.577571121543;8450.239478446969;8482.915170848213;7478.6260686966525;7568.4323675723435;7570.5836179010685;7571.053618297713;7574.204708505527;5505.256790333414;5685.317761868602;5820.534357251495;5833.416658334133;5837.94162058374;5756.65460072397;5767.826965191051;5860.38465961068;5911.467967882912;5986.491148252448 09:46:00 INFO - PROCESS | 20614 | |4;prototype.html;336.8214846131551;350.7632348164989;352.7020564323289;352.940000199966;353.717026378897;5583.413741557134;5607.9811267943605;5617.915731264035;5626.184203290521;5639.830805075849;5269.578433725303;5405.621606487547;5497.780088796453;5531.723413829302;5586.273784408021;4290.678199135062;4346.84786032489;4364.7817609119575;4367.803448844809;4391.868243952678;4369.737815731066;4371.781410929457;4375.890602734925;4381.6494680425485;4386.8025938832825;4126;4152.252594532983;4328.480582330125;4357.063231405247;4357.172137293904;4310.655147588217;4323.351497275403;4328.588784065509;4329.848455304065;4347.413099231595;3817.618238176188;3834.3481608126685;3839.6736277416453;3840.2511510255513;3849.4225866120028;5133.640645154841;5272.156454967225;5287.7884884604955;5288.524032837063;5365.973170134124;4953.306537084775;4961.337951786156;4963;4963.900721985576;4973.875653108665;5637.605367624268;5732.254806875121;5734.827955161351;5776.451237132508;5804.535637149018;4065.2885744994805;4220.198162349175;4226.175895700339;4251.426057482262;4252.915506545842;5956.851078723023;5956.910646340309;5989.730462129171;6000.879982400371;6026.578139530235;4329.978350108229;4337.956620433818;4339.82640694375;4344.717593356421;4349.652027837766;4291.3777502262;4366.279563871989;4387.912241755147;4415.527304709373;4423.358613001097;4888.95111048885;4899.191633380524;4899.240617704261;4899.58353539946;4926.482719314492;5670.489655930986;5732.856678583069;5766.452187042224;5831.475167234969;5860.179574859438;1442.2716528153217;1465.6775509387917;1501.7296886560305;1519.2479722537194;1520.7794869744046;948.8624149498285;962.4898803634085;974.0989584634185;984.4241118945354;986.7089208683456;5021.91152441246;5033.647644664911;5044.495550444926;5065.619158995266;5081.491850814963;5275.366955965309;5282.418933917266;5310.575153987749;5325.733713314319;5348.438413966555;4830.3237546743485;4998.450170481244;5018.5985121190215;5047.778977249998;5076.695398276115;2791.7906157037896;2794.9580756288265;2809.2415047936943;2814.887404503838;2835.560488124344;5352.518273355417;5384.91922621161;5408.02655522002;5479.7944452220445;5513.724313784375;5337.466253374631;5372.409035006146;5685.545156387479;6095.329513753483;6114.449699527064;6166.537509686791;6231.470325022424;6237.376262373817;6277.708636347609;6282.214723159514;5823.650580965155;5884.317958587452;6023.27720673522;6072.969635151796;6091.208142941389;5909.6158749681135;5938.673372964528;5974.761009559569;5980.641161530322;5987.371326010881;6217.347178546188;6254.21822272216;6264.713379616498;6332.968335158294;6375.904361434675;4130.607592278729;4333.631641310524;4383.145286669069;4385.956140438555;4388.934165987449;5787.160861674949;5988;5992.0712289595185;6001.849953751192;6008.35632321777;5784.161296612052;5861.003629383101;5884;5895.528357731457;5899;5859.20900678403;5872.500837428866;5879.235699359056;5905.645661260338;5929.792457264148;5585.748641311232;5772.7690892363835;5811.53507719373;5823.088455772114;5830.82507524775;5917.970410148094;5931.288245410578;5931.3178984417345;5935.258092738408;5959.135925290895;5408.161734931092;5786.817250512286;5794.942050579441;5846.590738648424;5858.209141765807 09:46:00 INFO - PROCESS | 20614 | |5;yui.html;803.2048272210513;820.6840366458912;846.7248144353086;879.5294517433173;903.4985583001434;15773.132477713722;15904.125273109974;15905.125218113013;15914.442994495195;16121.677566448663;15256.008359456655;16395.262213200403;17254.654906901887;17254.913725431354;17877.01676407797;890.0520945193365;891.6477991193507;893.5085702863431;896.090468174803;896.5651658945438;884.3500027479797;885.4775682347414;886.171429713217;887.4276091920707;887.4320434921656;937.1752857485403;937.6999360204737;937.779621788879;937.8077494113691;938.7230766923774;937.7561833923161;939.1031564855547;939.1547607153548;939.567798812547;939.7086903060039;685.5167107189419;685.5509641185032;686.1594546680327;686.6635348679112;686.8488932434856;15859.36562537497;15870.12714300713;15873.44442944503;15881.28534215963;15892.125933073676;15325.693486130214;15823.762643560356;15941.681166376608;16122;16409.83590164095;12602.74794504114;12748.745025099539;12874.935625321907;13099;13141.605751827365;4067.918641627151;4082.5917408259234;4112.691548133902;4151;4194.30793919003;78194;80356;80460.59769701114;80549.59725201395;81104;12225.144239903299;12286.692832679255;12363.876361236453;12393.442295096562;12403.937980310222;22557.54884902293;22561.323160305215;22562.774372256066;22563.88718056432;22583.54832903333;9015.549222538846;9025.142611451902;9047.773805654911;9251.44491330509;9329.953350233205;4752.548507891814;5149.098907691139;5260.9736951315;5301.443348448434;5367.490088441592;3910.1202229498135;4034.374671925856;4037.656799172103;4038.353863381845;4040.111175541435;654.7741029344868;668.7038653861429;668.752561552219;671.3890359772583;675.0920012583068;652.526917984467;688.4320435640617;704.1550139832119;711.8398360368875;744.5495475237511;10928.672139835817;10967.780644387067;11164.385958772184;11212.383318917375;11474.885251147549;743.8326376565337;745.5154149802563;754.4831790223715;769.7459838253363;792.0416296281563;4954.851354459372;5014.373203349582;5074.467180945948;5093.134167191586;5257.290265814143;102956;110043;112639.43680281546;112912.43543782392;113703.43148284206;64411.67794160999;74525;84452;99043;100770.49614752026;69724;90477.54761226152;90685.09314906898;91817.54091229501;92295.07704922999;10363.896361036595;10661.147108231478;10695.786084278427;10747.570097196025;10800.351978880977;46839.29741053819;47208.055838883716;47658.52341476541;49055.26417103818;49948;69670;73975;74115.62942185254;74153.25846741463;74316.62841685757;13483.595492135251;13534.255615941218;13542.05205635596;13643.727125457634;13755.10591811529;15119.244037798286;15237.923810380877;15248.923755381153;15855.68288634244;15986.760198596798;14806.481773137888;15070.321835517647;15357.232138392812;15764.211789410254;16203.75694364609;13542.322883855571;13543.322833858465;13550.32248387557;13866.653333666336;14043.15741055499;12832.165909215872;12962.481500739865;12962.935185324013;13108.803367949298;13580.728385432434;12205.816912746139;12219.69450763738;12441.75116497647;12716.809247861474;12821.487140514648;12324.383780811102;12357.443915023665;12467.87532124667;12574.434150463434;12581.245125292511 09:46:00 INFO - PROCESS | 20614 | __end_tp_report 09:46:00 INFO - PROCESS | 20614 | __start_cc_report 09:46:00 INFO - PROCESS | 20614 | _x_x_mozilla_cycle_collect,907 09:46:00 INFO - PROCESS | 20614 | __end_cc_report 09:46:00 INFO - PROCESS | 20614 | __startTimestamp1466527560173__endTimestamp 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - PROCESS | 20614 | ------- Summary: start ------- 09:46:00 INFO - PROCESS | 20614 | Number of tests: 6 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - PROCESS | 20614 | [#0] dojo.html Cycles:165 Average:5603.94 Median:5811.83 stddev:636.08 (10.9%) stddev-sans-first:614.49 09:46:00 INFO - PROCESS | 20614 | Values: 3418.2 3436.9 3437.1 3457.4 3495.1 5665.1 5741.0 5776.0 5792.8 5795.6 5486.0 5520.4 5522.6 5525.4 5534.3 4894.4 4981.6 4983.2 4987.1 5019.8 5035.5 5079.1 5083.3 5084.5 5085.2 5051.7 5082.9 5106.0 5110.2 5148.8 5187.5 5213.4 5214.4 5217.8 5244.2 4902.1 4944.1 4979.0 5118.2 5128.1 5479.1 5492.7 5496.1 5516.9 5522.7 6088.6 6088.8 6098.1 6114.0 6123.7 5951.8 6031.8 6040.3 6078.4 6118.6 5666.2 5683.4 5704.8 5710.3 5718.5 5948.7 6204.1 6204.1 6219.5 6222.9 5850.2 5920.6 5924.5 6026.8 6048.5 6076.8 6082.3 6083.9 6092.8 6115.1 5785.8 5973.1 6058.6 6063.3 6067.2 5991.2 6083.2 6087.7 6093.5 6129.2 4622.7 4638.8 4640.2 4643.7 4667.0 4026.0 4066.5 4077.8 4150.9 4160.4 5741.1 5760.3 5768.2 5769.2 5782.9 6025.8 6078.7 6079.0 6080.1 6103.5 5532.7 5589.6 5646.3 5646.4 5752.6 5046.1 5059.9 5060.4 5062.6 5087.2 6205.2 6240.8 6244.9 6246.4 6254.6 5794.4 5961.3 6025.8 6079.1 6084.1 6288.2 6340.0 6353.2 6362.0 6364.4 5966.5 5973.8 5977.1 5996.5 6004.7 5850.3 5932.2 5951.8 5961.9 6034.7 5837.2 5867.2 5868.0 5869.5 5871.6 5696.5 5738.4 5807.7 5811.8 5857.3 5818.5 5879.7 5899.6 5942.5 5943.8 6052.1 6100.4 6102.1 6102.5 6103.9 5603.4 5648.4 5928.3 5937.3 5937.5 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - PROCESS | 20614 | [#1] ext.html Cycles:180 Average:62864.68 Median:21791.42 stddev:124478.27 (571.2%) stddev-sans-first:124739.64 09:46:00 INFO - PROCESS | 20614 | Values: 588.5 639.6 646.8 658.7 670.4 32155.5 34899.8 46963.3 47600.3 47651.8 47608.3 47833.5 47920.0 47982.8 48119.8 8738.3 9098.8 9359.2 9366.4 11071.3 10789.6 11246.7 11252.7 11256.6 11257.2 11279.3 11328.6 11329.5 11334.9 11340.4 8593.8 11048.6 11175.6 11344.7 11419.6 10820.9 11235.6 11243.6 11249.9 11250.5 46211.5 46680.3 46692.0 46798.0 46811.5 3830.6 3915.3 3970.4 4224.5 4460.4 34979.8 34998.7 35113.8 35181.0 35377.8 4433.9 4452.3 4518.1 4583.5 4648.6 376544.0 380506.0 382200.0 382252.0 383343.0 40266.6 72408.0 73099.3 73288.6 73429.0 40600.0 42383.4 42444.0 42450.6 42668.6 15776.5 15958.5 16022.3 16060.8 16076.2 11855.7 12808.0 13081.3 13092.5 13332.1 9433.4 9444.4 9457.2 9507.8 9510.1 3520.4 3617.6 3622.1 3632.2 3674.3 1100.9 1112.3 1184.1 1193.9 1227.6 33948.0 34200.1 34345.8 34519.1 34878.3 1160.8 1209.4 1240.0 1269.8 1275.9 17830.3 17890.8 18029.0 18061.5 18095.8 575859.0 576613.0 577112.0 579261.0 581661.0 61294.7 62802.0 62925.0 63055.7 63104.4 1375.5 1460.9 1475.2 1488.3 1512.4 32614.5 33936.0 34049.1 34695.7 35294.5 52906.2 53408.2 53425.9 53718.7 54016.5 422978.0 424151.0 426246.0 426801.0 426873.0 28113.6 30578.0 31457.2 31490.4 32741.7 50230.0 51823.0 52468.5 53309.0 54072.0 43730.8 44105.8 44831.0 44900.1 45627.0 43646.7 44621.1 44726.8 44862.3 46194.8 44425.8 45357.1 45513.1 45665.8 45763.0 36399.6 39612.4 39879.2 40141.0 40187.8 29355.3 29598.6 29672.7 29972.6 30060.8 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - PROCESS | 20614 | [#2] jquery.html Cycles:180 Average:31364.35 Median:20492.14 stddev:88925.91 (434.0%) stddev-sans-first:89145.02 09:46:00 INFO - PROCESS | 20614 | Values: 424.4 427.1 434.1 435.4 451.1 18420.4 19519.5 20533.6 20551.2 20575.2 19799.3 19809.4 19881.5 20433.2 20450.7 5791.4 5993.9 6015.4 6015.8 6016.9 5585.0 5752.7 5756.3 5871.1 5915.9 5701.3 5703.8 5706.7 5724.4 5727.8 5656.4 5718.6 5815.4 5990.3 5998.9 5365.9 5379.1 5390.5 5390.6 5404.9 23016.0 24134.8 24578.0 24582.0 24653.3 2489.6 2527.7 2541.3 2578.1 2816.9 10095.2 10225.7 10811.0 10940.5 15303.8 1899.6 1963.2 1998.3 2023.9 2058.9 58468.0 83024.0 84178.0 85199.0 85885.6 11613.2 11810.2 11908.9 11920.6 12775.6 16625.3 16702.9 16722.4 16743.2 16762.7 7828.1 8013.8 8072.7 8148.3 8149.8 7702.3 7775.6 7775.8 7831.1 7933.8 5026.9 5157.5 5158.5 5165.7 5213.0 2136.1 2144.1 2153.7 2169.1 2203.6 963.5 1017.7 1037.0 1080.3 1133.2 19451.9 19787.9 20107.7 20375.4 20572.5 1022.5 1046.9 1063.2 1085.8 1092.6 9582.8 11381.3 11400.8 11481.8 11486.6 527066.0 529512.0 545417.0 549085.0 550275.0 97351.5 100936.0 101764.5 101831.0 101937.5 1101.5 1141.1 1158.0 1159.8 1163.0 17874.8 18331.3 18454.9 18465.4 18567.6 41117.8 42424.4 43339.8 43858.1 43911.1 59796.4 59814.0 60733.0 63643.4 64023.0 7375.2 11618.8 12119.6 12557.3 13250.9 13368.0 14568.3 14582.7 14722.9 15114.4 12536.3 12597.9 12738.6 12827.7 12922.9 12446.2 12636.8 12696.9 12854.0 12864.7 12765.7 12922.3 12954.6 13077.3 13349.2 13400.2 13419.5 13447.0 13469.1 13535.5 12317.9 12496.2 12506.5 12564.4 12659.9 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - PROCESS | 20614 | [#3] mootools.html Cycles:180 Average:6385.53 Median:4879.86 stddev:3720.44 (76.2%) stddev-sans-first:3712.15 09:46:00 INFO - PROCESS | 20614 | Values: 1418.2 1606.8 1645.5 1851.5 1964.0 9131.2 9403.8 9429.1 9432.1 9698.7 9085.8 9404.3 9714.2 9728.9 9729.5 2194.5 2252.2 2451.0 2501.7 2505.6 2517.5 2537.4 2542.2 2543.0 2543.5 2443.5 2479.6 2483.3 2483.7 2485.1 2135.4 2223.6 2278.1 2283.7 2369.5 2359.7 2368.7 2379.0 2379.5 2384.4 9658.0 9675.0 9710.4 9715.9 9718.2 5581.6 5790.8 5797.1 5797.7 5801.0 3500.5 3564.7 3618.2 3633.3 3639.6 1160.4 1176.3 1181.0 1231.3 1240.0 13430.3 13552.9 13600.2 13601.3 13623.0 4958.7 5037.6 5043.4 5079.9 5101.6 11276.2 11344.3 11345.5 11355.8 11358.3 5697.9 5702.3 5710.5 5710.7 5713.5 4562.2 5283.3 5384.3 5388.3 5516.6 4441.3 4467.3 4539.2 4543.6 4565.8 1391.6 1420.1 1424.6 1427.1 1442.7 1033.1 1044.1 1047.2 1052.2 1053.3 6250.9 6402.9 6484.5 6518.1 6581.6 501.7 505.4 506.8 511.7 520.0 4426.0 4498.6 4599.6 4774.3 4801.0 12111.3 12163.6 12275.7 12279.3 12458.7 11809.9 12160.1 12180.3 12228.6 12240.6 8816.0 9280.6 9319.5 9363.6 9415.1 6529.8 6666.3 6821.7 6825.3 6885.5 11298.3 11310.5 11338.5 11540.6 11635.7 11592.5 11638.1 11778.9 11780.6 11842.2 7676.8 8139.3 8243.8 8354.8 8547.1 9539.9 9568.2 9612.9 9641.3 9668.3 8487.1 8652.2 8657.1 8663.9 8815.1 8297.8 8417.4 8448.6 8450.2 8482.9 7478.6 7568.4 7570.6 7571.1 7574.2 5505.3 5685.3 5820.5 5833.4 5837.9 5756.7 5767.8 5860.4 5911.5 5986.5 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - PROCESS | 20614 | [#4] prototype.html Cycles:180 Average:4828.94 Median:5350.48 stddev:1421.65 (26.6%) stddev-sans-first:1385.08 09:46:00 INFO - PROCESS | 20614 | Values: 336.8 350.8 352.7 352.9 353.7 5583.4 5608.0 5617.9 5626.2 5639.8 5269.6 5405.6 5497.8 5531.7 5586.3 4290.7 4346.8 4364.8 4367.8 4391.9 4369.7 4371.8 4375.9 4381.6 4386.8 4126.0 4152.3 4328.5 4357.1 4357.2 4310.7 4323.4 4328.6 4329.8 4347.4 3817.6 3834.3 3839.7 3840.3 3849.4 5133.6 5272.2 5287.8 5288.5 5366.0 4953.3 4961.3 4963.0 4963.9 4973.9 5637.6 5732.3 5734.8 5776.5 5804.5 4065.3 4220.2 4226.2 4251.4 4252.9 5956.9 5956.9 5989.7 6000.9 6026.6 4330.0 4338.0 4339.8 4344.7 4349.7 4291.4 4366.3 4387.9 4415.5 4423.4 4889.0 4899.2 4899.2 4899.6 4926.5 5670.5 5732.9 5766.5 5831.5 5860.2 1442.3 1465.7 1501.7 1519.2 1520.8 948.9 962.5 974.1 984.4 986.7 5021.9 5033.6 5044.5 5065.6 5081.5 5275.4 5282.4 5310.6 5325.7 5348.4 4830.3 4998.5 5018.6 5047.8 5076.7 2791.8 2795.0 2809.2 2814.9 2835.6 5352.5 5384.9 5408.0 5479.8 5513.7 5337.5 5372.4 5685.5 6095.3 6114.4 6166.5 6231.5 6237.4 6277.7 6282.2 5823.7 5884.3 6023.3 6073.0 6091.2 5909.6 5938.7 5974.8 5980.6 5987.4 6217.3 6254.2 6264.7 6333.0 6375.9 4130.6 4333.6 4383.1 4386.0 4388.9 5787.2 5988.0 5992.1 6001.8 6008.4 5784.2 5861.0 5884.0 5895.5 5899.0 5859.2 5872.5 5879.2 5905.6 5929.8 5585.7 5772.8 5811.5 5823.1 5830.8 5918.0 5931.3 5931.3 5935.3 5959.1 5408.2 5786.8 5794.9 5846.6 5858.2 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - PROCESS | 20614 | [#5] yui.html Cycles:180 Average:20801.07 Median:4054.01 stddev:28641.96 (706.5%) stddev-sans-first:28682.95 09:46:00 INFO - PROCESS | 20614 | Values: 803.2 820.7 846.7 879.5 903.5 15773.1 15904.1 15905.1 15914.4 16121.7 15256.0 16395.3 17254.7 17254.9 17877.0 890.1 891.6 893.5 896.1 896.6 884.4 885.5 886.2 887.4 887.4 937.2 937.7 937.8 937.8 938.7 937.8 939.1 939.2 939.6 939.7 685.5 685.6 686.2 686.7 686.8 15859.4 15870.1 15873.4 15881.3 15892.1 15325.7 15823.8 15941.7 16122.0 16409.8 12602.7 12748.7 12874.9 13099.0 13141.6 4067.9 4082.6 4112.7 4151.0 4194.3 78194.0 80356.0 80460.6 80549.6 81104.0 12225.1 12286.7 12363.9 12393.4 12403.9 22557.5 22561.3 22562.8 22563.9 22583.5 9015.5 9025.1 9047.8 9251.4 9330.0 4752.5 5149.1 5261.0 5301.4 5367.5 3910.1 4034.4 4037.7 4038.4 4040.1 654.8 668.7 668.8 671.4 675.1 652.5 688.4 704.2 711.8 744.5 10928.7 10967.8 11164.4 11212.4 11474.9 743.8 745.5 754.5 769.7 792.0 4954.9 5014.4 5074.5 5093.1 5257.3 102956.0 110043.0 112639.4 112912.4 113703.4 64411.7 74525.0 84452.0 99043.0 100770.5 69724.0 90477.5 90685.1 91817.5 92295.1 10363.9 10661.1 10695.8 10747.6 10800.4 46839.3 47208.1 47658.5 49055.3 49948.0 69670.0 73975.0 74115.6 74153.3 74316.6 13483.6 13534.3 13542.1 13643.7 13755.1 15119.2 15237.9 15248.9 15855.7 15986.8 14806.5 15070.3 15357.2 15764.2 16203.8 13542.3 13543.3 13550.3 13866.7 14043.2 12832.2 12962.5 12962.9 13108.8 13580.7 12205.8 12219.7 12441.8 12716.8 12821.5 12324.4 12357.4 12467.9 12574.4 12581.2 09:46:00 INFO - PROCESS | 20614 | -------- Summary: end -------- 09:46:00 INFO - PROCESS | 20614 | 09:46:00 INFO - TEST-INFO | 20614: exit 0 09:46:00 INFO - TEST-OK | dromaeo_css | took 1111982ms 09:46:00 INFO - TEST-START | kraken 09:46:00 INFO - operating with platform_type : linux_ 09:46:00 INFO - Initialising browser for kraken test... 09:46:00 INFO - TEST-INFO | started process 20803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcXJLnc/profile http://localhost:39818/getInfo.html) 09:46:04 INFO - PROCESS | 20803 | __metrics Screen width/height:1600/1200 09:46:04 INFO - PROCESS | 20803 | colorDepth:24 09:46:04 INFO - PROCESS | 20803 | Browser inner width/height: 1024/697 09:46:04 INFO - PROCESS | 20803 | __metrics 09:46:05 ERROR - PROCESS | 20803 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:46:05 INFO - PROCESS | 20803 | console.error: PushService: 09:46:05 INFO - PROCESS | 20803 | stateChangeProcessEnqueue: Error transitioning state 09:46:05 INFO - PROCESS | 20803 | UnknownError 09:46:06 INFO - TEST-INFO | 20803: exit 0 09:46:06 INFO - Browser initialized. 09:46:06 INFO - Running cycle 1/1 for kraken test... 09:46:06 INFO - TEST-INFO | started process 20895 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcXJLnc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:18 INFO - PROCESS | 20895 | RSS: Main: 145559552 09:46:18 INFO - PROCESS | 20895 | 09:46:20 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39818/tests/kraken/driver.html?testName=audio-beat-detection) 09:46:21 INFO - PROCESS | 20895 | RSS: Main: 194555904 09:46:21 INFO - PROCESS | 20895 | 09:46:24 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39818/tests/kraken/driver.html?testName=audio-dft) 09:46:24 INFO - PROCESS | 20895 | RSS: Main: 192856064 09:46:24 INFO - PROCESS | 20895 | 09:46:27 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39818/tests/kraken/driver.html?testName=audio-fft) 09:46:28 INFO - PROCESS | 20895 | RSS: Main: 278446080 09:46:28 INFO - PROCESS | 20895 | 09:46:30 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39818/tests/kraken/driver.html?testName=audio-oscillator) 09:46:30 INFO - PROCESS | 20895 | RSS: Main: 187531264 09:46:30 INFO - PROCESS | 20895 | 09:46:33 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39818/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:46:33 INFO - PROCESS | 20895 | RSS: Main: 187830272 09:46:33 INFO - PROCESS | 20895 | 09:46:36 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39818/tests/kraken/driver.html?testName=imaging-darkroom) 09:46:37 INFO - PROCESS | 20895 | RSS: Main: 223125504 09:46:37 INFO - PROCESS | 20895 | 09:46:40 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39818/tests/kraken/driver.html?testName=imaging-desaturate) 09:46:41 INFO - PROCESS | 20895 | RSS: Main: 222965760 09:46:41 INFO - PROCESS | 20895 | 09:46:43 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39818/tests/kraken/driver.html?testName=json-parse-financial) 09:46:44 INFO - PROCESS | 20895 | RSS: Main: 223203328 09:46:44 INFO - PROCESS | 20895 | 09:46:46 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39818/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:46:46 INFO - PROCESS | 20895 | RSS: Main: 201293824 09:46:46 INFO - PROCESS | 20895 | 09:46:48 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39818/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:46:48 INFO - PROCESS | 20895 | RSS: Main: 202235904 09:46:48 INFO - PROCESS | 20895 | 09:46:50 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39818/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:46:51 INFO - PROCESS | 20895 | RSS: Main: 209448960 09:46:51 INFO - PROCESS | 20895 | 09:46:54 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39818/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:46:54 INFO - PROCESS | 20895 | RSS: Main: 217722880 09:46:54 INFO - PROCESS | 20895 | 09:46:57 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39818/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:46:57 INFO - PROCESS | 20895 | RSS: Main: 217665536 09:46:57 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | Cycle 1(1): loaded http://localhost:39818/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39818/tests/kraken/driver.html?testName=ai-astar) 09:46:59 INFO - PROCESS | 20895 | RSS: Main: 260673536 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | __start_tp_report 09:46:59 INFO - PROCESS | 20895 | _x_x_mozilla_page_load 09:46:59 INFO - PROCESS | 20895 | _x_x_mozilla_page_load_details 09:46:59 INFO - PROCESS | 20895 | |i|pagename|runs| 09:46:59 INFO - PROCESS | 20895 | |0;ai-astar;109;103;109;109;109;110;108;109;109;108 09:46:59 INFO - PROCESS | 20895 | |1;audio-beat-detection;165;130;126;167;126;125;155;129;151;130 09:46:59 INFO - PROCESS | 20895 | |2;audio-dft;162;161;160;159;160;160;159;161;160;163 09:46:59 INFO - PROCESS | 20895 | |3;audio-fft;74;76;75;128;76;75;73;75;100;74 09:46:59 INFO - PROCESS | 20895 | |4;audio-oscillator;86;84;113;83;83;86;117;84;83;140 09:46:59 INFO - PROCESS | 20895 | |5;imaging-gaussian-blur;116;117;116;117;116;117;118;116;118;118 09:46:59 INFO - PROCESS | 20895 | |6;imaging-darkroom;146;146;145;145;146;146;146;146;145;145 09:46:59 INFO - PROCESS | 20895 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 09:46:59 INFO - PROCESS | 20895 | |8;json-parse-financial;49;50;51;50;51;51;51;51;50;54 09:46:59 INFO - PROCESS | 20895 | |9;json-stringify-tinderbox;69;60;60;60;60;60;61;60;60;60 09:46:59 INFO - PROCESS | 20895 | |10;stanford-crypto-aes;76;77;79;76;77;77;78;77;101;77 09:46:59 INFO - PROCESS | 20895 | |11;stanford-crypto-ccm;119;125;116;116;119;122;125;127;140;130 09:46:59 INFO - PROCESS | 20895 | |12;stanford-crypto-pbkdf2;161;155;155;157;156;161;155;156;160;155 09:46:59 INFO - PROCESS | 20895 | |13;stanford-crypto-sha256-iterative;63;63;62;61;62;62;62;60;60;63 09:46:59 INFO - PROCESS | 20895 | __end_tp_report 09:46:59 INFO - PROCESS | 20895 | __start_cc_report 09:46:59 INFO - PROCESS | 20895 | _x_x_mozilla_cycle_collect,1534 09:46:59 INFO - PROCESS | 20895 | __end_cc_report 09:46:59 INFO - PROCESS | 20895 | __startTimestamp1466527619837__endTimestamp 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | ------- Summary: start ------- 09:46:59 INFO - PROCESS | 20895 | Number of tests: 14 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#0] ai-astar Cycles:10 Average:108.30 Median:109.00 stddev:1.95 (1.8%) stddev-sans-first:2.05 09:46:59 INFO - PROCESS | 20895 | Values: 109.0 103.0 109.0 109.0 109.0 110.0 108.0 109.0 109.0 108.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:140.50 stddev:17.12 (12.2%) stddev-sans-first:15.67 09:46:59 INFO - PROCESS | 20895 | Values: 165.0 130.0 126.0 167.0 126.0 125.0 155.0 129.0 151.0 130.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:1.27 (0.8%) stddev-sans-first:1.22 09:46:59 INFO - PROCESS | 20895 | Values: 162.0 161.0 160.0 159.0 160.0 160.0 159.0 161.0 160.0 163.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:17.84 (23.8%) stddev-sans-first:18.65 09:46:59 INFO - PROCESS | 20895 | Values: 74.0 76.0 75.0 128.0 76.0 75.0 73.0 75.0 100.0 74.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:83.50 stddev:20.17 (24.2%) stddev-sans-first:21.07 09:46:59 INFO - PROCESS | 20895 | Values: 86.0 84.0 113.0 83.0 83.0 86.0 117.0 84.0 83.0 140.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.87 09:46:59 INFO - PROCESS | 20895 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 118.0 116.0 118.0 118.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:46:59 INFO - PROCESS | 20895 | Values: 146.0 146.0 145.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:46:59 INFO - PROCESS | 20895 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:1.32 (2.6%) stddev-sans-first:1.22 09:46:59 INFO - PROCESS | 20895 | Values: 49.0 50.0 51.0 50.0 51.0 51.0 51.0 51.0 50.0 54.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:60.00 stddev:2.83 (4.7%) stddev-sans-first:0.33 09:46:59 INFO - PROCESS | 20895 | Values: 69.0 60.0 60.0 60.0 60.0 60.0 61.0 60.0 60.0 60.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:77.00 stddev:7.60 (9.9%) stddev-sans-first:7.96 09:46:59 INFO - PROCESS | 20895 | Values: 76.0 77.0 79.0 76.0 77.0 77.0 78.0 77.0 101.0 77.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:125.00 stddev:7.34 (5.9%) stddev-sans-first:7.57 09:46:59 INFO - PROCESS | 20895 | Values: 119.0 125.0 116.0 116.0 119.0 122.0 125.0 127.0 140.0 130.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.10 Median:156.50 stddev:2.56 (1.6%) stddev-sans-first:2.29 09:46:59 INFO - PROCESS | 20895 | Values: 161.0 155.0 155.0 157.0 156.0 161.0 155.0 156.0 160.0 155.0 09:46:59 INFO - PROCESS | 20895 | 09:46:59 INFO - PROCESS | 20895 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.14 (1.8%) stddev-sans-first:1.12 09:46:59 INFO - PROCESS | 20895 | Values: 63.0 63.0 62.0 61.0 62.0 62.0 62.0 60.0 60.0 63.0 09:46:59 INFO - PROCESS | 20895 | -------- Summary: end -------- 09:46:59 INFO - PROCESS | 20895 | 09:47:00 INFO - TEST-INFO | 20895: exit 0 09:47:00 INFO - TEST-OK | kraken | took 59996ms 09:47:00 INFO - SUITE-END | took 1171s 09:47:01 INFO - Completed test suite (00:19:32) 09:47:01 INFO - javascript benchmark 09:47:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3418.2308980479397, 3436.948445773314, 3437.0719905625488, 3457.4468085106387, 3495.143689796, 5665.093585026397, 5741.0240259155935, 5776.0, 5792.76828926843, 5795.623284486505, 5486.012517746804, 5520.392756796749, 5522.585806064541, 5525.364583072947, 5534.252875861757, 4894.4371397289415, 4981.576565991895, 4983.152864013126, 4987.102321582114, 5019.824306149286, 5035.546800787928, 5079.060373830853, 5083.313752643401, 5084.491550844923, 5085.211792172201, 5051.722155281458, 5082.923756143661, 5105.974470127663, 5110.156824124015, 5148.768305426265, 5187.533122019036, 5213.3813114803315, 5214.426413094557, 5217.843464696065, 5244.187150991603, 4902.093112774148, 4944.085344211332, 4978.950210497885, 5118.155504341779, 5128.051310507568, 5479.068558345091, 5492.670439773627, 5496.0931446311315, 5516.862078448031, 5522.66863988162, 6088.645418326693, 6088.817335479943, 6098.08528720691, 6113.969430152865, 6123.663198524079, 5951.761929522814, 6031.849203769897, 6040.335563088057, 6078.361772013919, 6118.632882027093, 5666.150077488369, 5683.403242659544, 5704.828855134311, 5710.2862142232225, 5718.542516598662, 5948.702564871825, 6204.100405441276, 6204.13142160098, 6219.502439804804, 6222.906656400158, 5850.210221620112, 5920.555958303058, 5924.4667979882015, 6026.849328766816, 6048.546359023091, 6076.848078797977, 6082.255840444372, 6083.878322433527, 6092.786752463642, 6115.113308570232, 5785.797497087666, 5973.133895585116, 6058.606190597597, 6063.272407311151, 6067.24159480065, 5991.191189189403, 6083.178770865877, 6087.69561521931, 6093.482054025369, 6129.233845769279, 4622.745748983838, 4638.768061596907, 4640.164770341371, 4643.6517261205545, 4667.0, 4025.9597404025585, 4066.471358723347, 4077.7961101944784, 4150.91698166041, 4160.375943608423, 5741.052726300197, 5760.308762948473, 5768.192453056576, 5769.221155144001, 5782.855428614234, 6025.8192254232445, 6078.665673388006, 6079.0, 6080.0879868019265, 6103.511719062464, 5532.723363831792, 5589.552835773128, 5646.322441307069, 5646.378898321182, 5752.597318187687, 5046.066477701637, 5059.924101138486, 5060.418051924003, 5062.594992400599, 5087.211482220262, 6205.162303089115, 6240.843978900473, 6244.937550624527, 6246.406591373812, 6254.562180647311, 5794.449527294901, 5961.2846458425265, 6025.758969641165, 6079.05774604937, 6084.148219249397, 6288.213973253343, 6340.0, 6353.174087368612, 6362.013887847391, 6364.363563643598, 5966.463018328372, 5973.790917317873, 5977.103434484862, 5996.4603185713495, 6004.729787159676, 5850.327212370462, 5932.16949627044, 5951.791687291097, 5961.910571341346, 6034.698265086816, 5837.153612726216, 5867.178594996789, 5867.970660146672, 5869.4717475427415, 5871.647701137775, 5696.458836410535, 5738.368779434176, 5807.680577568274, 5811.82564523065, 5857.326407463196, 5818.4763371296785, 5879.676617785892, 5899.616524925866, 5942.494887934574, 5943.821685349447, 6052.061930400795, 6100.389961004042, 6102.115193297036, 6102.4507794298725, 6103.938960610337, 5603.439656034364, 5648.435156484319, 5928.318243402064, 5937.317208521075, 5937.495312898452], "value": 5562.424549905156, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.5174157191102, 639.6258188959458, 646.7671638210244, 658.6969993802851, 670.3966430212805, 32155.51766723498, 34899.82550087249, 46963.29555056677, 47600.285995710095, 47651.76174119124, 47608.28587571181, 47833.521664783344, 47920.0, 47982.76008619961, 48119.75940120303, 8738.300935925143, 9098.772530686736, 9359.20446762026, 9366.438013719164, 11071.335719856794, 10789.568417263301, 11246.718832029183, 11252.66242012741, 11256.606018789344, 11257.155713321492, 11279.32324060559, 11328.603498877541, 11329.546818127264, 11334.943325283362, 11340.432978351091, 8593.828123437528, 11048.613298534594, 11175.608853690122, 11344.659660210207, 11419.600313989054, 10820.891791082067, 11235.550577976872, 11243.60647377338, 11249.943750281278, 11250.49372778227, 46211.53788462139, 46680.299795503095, 46692.0, 46798.0, 46811.531884681055, 3830.616938306175, 3915.295246855565, 3970.4441378206784, 4224.45082139323, 4460.442444694413, 34979.82510087459, 34998.65001349979, 35113.82443087794, 35181.0, 35377.82311088428, 4433.866983990485, 4452.332150177499, 4518.118966801475, 4583.518730533279, 4648.604868586174, 376544.0, 380506.0, 382200.0, 382252.0, 383343.0, 40266.59733402687, 72408.0, 73099.26900730925, 73288.63355683188, 73429.0, 40600.0, 42383.36424953566, 42444.0, 42450.575494244666, 42668.57331426646, 15776.526704198892, 15958.521244362688, 16022.278997445143, 16060.75908861368, 16076.196190190678, 11855.70360740971, 12808.0, 13081.345932703518, 13092.476300947856, 13332.133411328232, 9433.433993960385, 9444.433333999981, 9457.19613832832, 9507.809843803085, 9510.144087032062, 3520.437708420689, 3617.6382361763613, 3622.1125824173246, 3632.2372301816386, 3674.2835147146584, 1100.8954149355798, 1112.260346869339, 1184.1178322150054, 1193.8837187229967, 1227.613301809927, 33948.0, 34200.14499637529, 34345.82827085898, 34519.137021574665, 34878.302433951176, 1160.7504386556932, 1209.4013463335705, 1240.0053869904082, 1269.7587458382877, 1275.9298238596966, 17830.286788528574, 17890.821091789177, 18029.0, 18061.548461288574, 18095.823771454812, 575859.0, 576613.0, 577112.0, 579261.0, 581661.0, 61294.69352653208, 62802.0, 62925.0, 63055.6847215761, 63104.36895630985, 1375.4910683047137, 1460.9269536523132, 1475.1517877220426, 1488.3079368093888, 1512.4177191781023, 32614.51078233781, 33936.0, 34049.14877128092, 34695.65304347025, 35294.47058294179, 52906.206406903155, 53408.198877017654, 53425.931481370935, 53718.73140634272, 54016.45983540272, 422978.0, 424151.0, 426246.0, 426801.0, 426873.0, 28113.578296325162, 30578.0, 31457.21356966094, 31490.370192596478, 32741.672583274816, 50230.0, 51823.0, 52468.47531524636, 53309.0, 54072.0, 43730.78134609306, 44105.77947110244, 44831.0, 44900.11616138918, 45627.0, 43646.74628199767, 44621.107577847615, 44726.77636611796, 44862.3270650934, 46194.76902615465, 44425.77787111044, 45357.09285814331, 45513.089738205716, 45665.77167114143, 45763.0, 36399.636003640684, 39612.40581391339, 39879.20241595094, 40141.0, 40187.79906100451, 29355.266118346357, 29598.55602165926, 29672.703272967858, 29972.550411744276, 30060.84969575138], "value": 20758.09707459265, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.4397395438021, 427.1137389915925, 434.1057421711275, 435.37958409266787, 451.1225666079475, 18420.355287564937, 19519.512012199684, 20533.589328213464, 20551.177952881866, 20575.176992920286, 19799.307024254154, 19809.405717828453, 19881.50296242591, 20433.18267269311, 20450.693239601416, 5791.391903850098, 5993.9400605993815, 6015.398460153993, 6015.849603759897, 6016.909746353808, 5585.0, 5752.654840709571, 5756.3092428908585, 5871.119332100197, 5915.852103697399, 5701.344345400291, 5703.800366987156, 5706.7431965561445, 5724.370319264898, 5727.799527016556, 5656.377798442168, 5718.571107166958, 5815.38938411467, 5990.311114221833, 5998.8500287492725, 5365.892682146336, 5379.1393377059485, 5390.460953904618, 5390.56875449967, 5404.864878378072, 23016.0, 24134.758652413428, 24578.0, 24582.0, 24653.260402187785, 2489.593459458116, 2527.734587868266, 2541.3265484646586, 2578.1234380310784, 2816.901408450715, 10095.192384609214, 10225.693229203136, 10811.0, 10940.4529773511, 15303.846961530353, 1899.6105798311312, 1963.1754663041556, 1998.3305592626486, 2023.9190432382834, 2058.8661736987046, 58468.0, 83024.0, 84178.0, 85199.0, 85885.57057214674, 11613.245139065933, 11810.232334898204, 11908.880911190778, 11920.582779602672, 12775.61673149807, 16625.251863666163, 16702.91648541774, 16722.414715484898, 16743.16284185786, 16762.74855877163, 7828.138904720477, 8013.759587212393, 8072.677092916335, 8148.307393871587, 8149.755507334789, 7702.3453006493955, 7775.611219439005, 7775.766726998199, 7831.099423566363, 7933.801654958556, 5026.924596131061, 5157.535821775983, 5158.535731784157, 5165.741712914414, 5213.035588416155, 2136.134865379524, 2144.0780464400145, 2153.7307836520436, 2169.1323470611883, 2203.559288142378, 963.4556475591269, 1017.6896046705825, 1036.9792604147874, 1080.287010573018, 1133.2350663302402, 19451.90274048649, 19787.901060494605, 20107.698384523952, 20375.388738337875, 20572.485687857923, 1022.5143057047992, 1046.858674079005, 1063.2079101069753, 1085.7882712871071, 1092.639428988432, 9582.808343833085, 11381.317120972768, 11400.771984560428, 11481.770364592661, 11486.59796907121, 527066.0, 529512.0, 545417.0, 549085.0, 550275.0, 97351.51324243475, 100936.0, 101764.49117754512, 101831.0, 101937.49031254945, 1101.493313075994, 1141.058626633031, 1157.9710507237387, 1159.837622732818, 1163.040491594438, 17874.821251787314, 18331.266749329876, 18454.907725461286, 18465.446036618923, 18567.628647427246, 41117.79441102775, 42424.36363454489, 43339.78330108329, 43858.122837543706, 43911.12177756363, 59796.40203597908, 59814.0, 60733.0, 63643.36356636375, 64023.0, 7375.151857536237, 11618.76762464763, 12119.636410907688, 12557.309347985949, 13250.867491324963, 13368.0, 14568.27158642085, 14582.708345832812, 14722.852771472148, 15114.395424183349, 12536.310502922426, 12597.87402125967, 12738.617841464771, 12827.743445131231, 12922.935385323013, 12446.190997585129, 12636.810447843105, 12696.936515317364, 12853.97168226521, 12864.678383040498, 12765.680857978254, 12922.289274090015, 12954.611361659165, 13077.280749558866, 13349.199048057148, 13400.195988240346, 13419.463221471422, 13447.0, 13469.057165998678, 13535.526256580972, 12317.876821232032, 12496.187747796728, 12506.499740010298, 12564.37178141071, 12659.87340126587], "value": 9919.225841560423, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.2270662488943, 1606.750953602192, 1645.5227983884674, 1851.5093500222442, 1964.0474369930582, 9131.223845973092, 9403.764905877357, 9429.057094290567, 9432.056794320573, 9698.70903872885, 9085.772855678595, 9404.341696081277, 9714.222862171027, 9728.902710972889, 9729.513524323791, 2194.517206214638, 2252.2117258959397, 2450.950980980384, 2501.6747822783013, 2505.6366826810104, 2517.471331020488, 2537.3783423061377, 2542.2373288013523, 2543.0209369392733, 2543.529447052292, 2443.4746529496156, 2479.566075936713, 2483.3295010347197, 2483.677121974141, 2485.142625794107, 2135.370065830584, 2223.5664045511053, 2278.0773786616282, 2283.7373702024147, 2369.455025344172, 2359.740428552858, 2368.7157541095007, 2378.988105059481, 2379.5240951809706, 2384.3800611840875, 9658.0, 9674.9516252419, 9710.368826026286, 9715.854262186072, 9718.173955213815, 5581.6371935824045, 5790.826275211751, 5797.072468405035, 5797.710114494258, 5801.0, 3500.5449291592186, 3564.6613571710905, 3618.185908170667, 3633.309671162497, 3639.5996440391536, 1160.4371879638368, 1176.288345550938, 1180.9704757381048, 1231.279701374697, 1239.9814002789965, 13430.261335626537, 13552.93223533876, 13600.183988960694, 13601.251931143783, 13623.0, 4958.727270000112, 5037.571806396498, 5043.445221025684, 5079.898402032012, 5101.617378696613, 11276.210665253353, 11344.262622929484, 11345.489452974636, 11355.772884542428, 11358.26171298863, 5697.914531282034, 5702.315722113373, 5710.543156547466, 5710.685912274865, 5713.514351280188, 4562.201614717411, 5283.339582552181, 5384.273123128406, 5388.326459192601, 5516.586256030814, 4441.267190913528, 4467.329900514948, 4539.228331183706, 4543.568361005699, 4565.817367305337, 1391.589481103077, 1420.1053336398004, 1424.5655075201955, 1427.108057464085, 1442.740306744796, 1033.1476531861244, 1044.1072882685346, 1047.2459828923163, 1052.221356196409, 1053.2890299048113, 6250.9062364064575, 6402.9359706403275, 6484.481241500669, 6518.054882042077, 6581.572197807127, 501.7115158783714, 505.39605171820057, 506.7972810875679, 511.6597462687346, 519.9532042116227, 4425.955740442554, 4498.617617502549, 4599.609033232213, 4774.331593576902, 4801.0, 12111.333876636694, 12163.574274900513, 12275.69310767238, 12279.324637145042, 12458.688532786571, 11809.878061584135, 12160.08799340053, 12180.269183848997, 12228.571999979958, 12240.571579994657, 8816.0, 9280.582373793195, 9319.534023298807, 9363.578638961128, 9415.05849415053, 6529.804105876831, 6666.300038495888, 6821.727130914906, 6825.283345248876, 6885.51801373899, 11298.265612735146, 11310.4910279036, 11338.527619618353, 11540.596079137189, 11635.650930472098, 11592.536298547964, 11638.127140464669, 11778.941105294762, 11780.646580602595, 11842.230255033395, 7676.769696909102, 8139.267465928095, 8243.752687419386, 8354.832903342021, 8547.145285471652, 9539.856902146335, 9568.186704130227, 9612.855807163038, 9641.27690423207, 9668.323217374998, 8487.066422693622, 8652.22130008277, 8657.091005444337, 8663.870041949249, 8815.074417186359, 8297.83404331922, 8417.410781245255, 8448.577571121543, 8450.239478446969, 8482.915170848213, 7478.6260686966525, 7568.4323675723435, 7570.5836179010685, 7571.053618297713, 7574.204708505527, 5505.256790333414, 5685.317761868602, 5820.534357251495, 5833.416658334133, 5837.94162058374, 5756.65460072397, 5767.826965191051, 5860.38465961068, 5911.467967882912, 5986.491148252448], "value": 4978.856522659121, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.8214846131551, 350.7632348164989, 352.7020564323289, 352.940000199966, 353.717026378897, 5583.413741557134, 5607.9811267943605, 5617.915731264035, 5626.184203290521, 5639.830805075849, 5269.578433725303, 5405.621606487547, 5497.780088796453, 5531.723413829302, 5586.273784408021, 4290.678199135062, 4346.84786032489, 4364.7817609119575, 4367.803448844809, 4391.868243952678, 4369.737815731066, 4371.781410929457, 4375.890602734925, 4381.6494680425485, 4386.8025938832825, 4126.0, 4152.252594532983, 4328.480582330125, 4357.063231405247, 4357.172137293904, 4310.655147588217, 4323.351497275403, 4328.588784065509, 4329.848455304065, 4347.413099231595, 3817.618238176188, 3834.3481608126685, 3839.6736277416453, 3840.2511510255513, 3849.4225866120028, 5133.640645154841, 5272.156454967225, 5287.7884884604955, 5288.524032837063, 5365.973170134124, 4953.306537084775, 4961.337951786156, 4963.0, 4963.900721985576, 4973.875653108665, 5637.605367624268, 5732.254806875121, 5734.827955161351, 5776.451237132508, 5804.535637149018, 4065.2885744994805, 4220.198162349175, 4226.175895700339, 4251.426057482262, 4252.915506545842, 5956.851078723023, 5956.910646340309, 5989.730462129171, 6000.879982400371, 6026.578139530235, 4329.978350108229, 4337.956620433818, 4339.82640694375, 4344.717593356421, 4349.652027837766, 4291.3777502262, 4366.279563871989, 4387.912241755147, 4415.527304709373, 4423.358613001097, 4888.95111048885, 4899.191633380524, 4899.240617704261, 4899.58353539946, 4926.482719314492, 5670.489655930986, 5732.856678583069, 5766.452187042224, 5831.475167234969, 5860.179574859438, 1442.2716528153217, 1465.6775509387917, 1501.7296886560305, 1519.2479722537194, 1520.7794869744046, 948.8624149498285, 962.4898803634085, 974.0989584634185, 984.4241118945354, 986.7089208683456, 5021.91152441246, 5033.647644664911, 5044.495550444926, 5065.619158995266, 5081.491850814963, 5275.366955965309, 5282.418933917266, 5310.575153987749, 5325.733713314319, 5348.438413966555, 4830.3237546743485, 4998.450170481244, 5018.5985121190215, 5047.778977249998, 5076.695398276115, 2791.7906157037896, 2794.9580756288265, 2809.2415047936943, 2814.887404503838, 2835.560488124344, 5352.518273355417, 5384.91922621161, 5408.02655522002, 5479.7944452220445, 5513.724313784375, 5337.466253374631, 5372.409035006146, 5685.545156387479, 6095.329513753483, 6114.449699527064, 6166.537509686791, 6231.470325022424, 6237.376262373817, 6277.708636347609, 6282.214723159514, 5823.650580965155, 5884.317958587452, 6023.27720673522, 6072.969635151796, 6091.208142941389, 5909.6158749681135, 5938.673372964528, 5974.761009559569, 5980.641161530322, 5987.371326010881, 6217.347178546188, 6254.21822272216, 6264.713379616498, 6332.968335158294, 6375.904361434675, 4130.607592278729, 4333.631641310524, 4383.145286669069, 4385.956140438555, 4388.934165987449, 5787.160861674949, 5988.0, 5992.0712289595185, 6001.849953751192, 6008.35632321777, 5784.161296612052, 5861.003629383101, 5884.0, 5895.528357731457, 5899.0, 5859.20900678403, 5872.500837428866, 5879.235699359056, 5905.645661260338, 5929.792457264148, 5585.748641311232, 5772.7690892363835, 5811.53507719373, 5823.088455772114, 5830.82507524775, 5917.970410148094, 5931.288245410578, 5931.3178984417345, 5935.258092738408, 5959.135925290895, 5408.161734931092, 5786.817250512286, 5794.942050579441, 5846.590738648424, 5858.209141765807], "value": 4377.4407120157175, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.2048272210513, 820.6840366458912, 846.7248144353086, 879.5294517433173, 903.4985583001434, 15773.132477713722, 15904.125273109974, 15905.125218113013, 15914.442994495195, 16121.677566448663, 15256.008359456655, 16395.262213200403, 17254.654906901887, 17254.913725431354, 17877.01676407797, 890.0520945193365, 891.6477991193507, 893.5085702863431, 896.090468174803, 896.5651658945438, 884.3500027479797, 885.4775682347414, 886.171429713217, 887.4276091920707, 887.4320434921656, 937.1752857485403, 937.6999360204737, 937.779621788879, 937.8077494113691, 938.7230766923774, 937.7561833923161, 939.1031564855547, 939.1547607153548, 939.567798812547, 939.7086903060039, 685.5167107189419, 685.5509641185032, 686.1594546680327, 686.6635348679112, 686.8488932434856, 15859.36562537497, 15870.12714300713, 15873.44442944503, 15881.28534215963, 15892.125933073676, 15325.693486130214, 15823.762643560356, 15941.681166376608, 16122.0, 16409.83590164095, 12602.74794504114, 12748.745025099539, 12874.935625321907, 13099.0, 13141.605751827365, 4067.918641627151, 4082.5917408259234, 4112.691548133902, 4151.0, 4194.30793919003, 78194.0, 80356.0, 80460.59769701114, 80549.59725201395, 81104.0, 12225.144239903299, 12286.692832679255, 12363.876361236453, 12393.442295096562, 12403.937980310222, 22557.54884902293, 22561.323160305215, 22562.774372256066, 22563.88718056432, 22583.54832903333, 9015.549222538846, 9025.142611451902, 9047.773805654911, 9251.44491330509, 9329.953350233205, 4752.548507891814, 5149.098907691139, 5260.9736951315, 5301.443348448434, 5367.490088441592, 3910.1202229498135, 4034.374671925856, 4037.656799172103, 4038.353863381845, 4040.111175541435, 654.7741029344868, 668.7038653861429, 668.752561552219, 671.3890359772583, 675.0920012583068, 652.526917984467, 688.4320435640617, 704.1550139832119, 711.8398360368875, 744.5495475237511, 10928.672139835817, 10967.780644387067, 11164.385958772184, 11212.383318917375, 11474.885251147549, 743.8326376565337, 745.5154149802563, 754.4831790223715, 769.7459838253363, 792.0416296281563, 4954.851354459372, 5014.373203349582, 5074.467180945948, 5093.134167191586, 5257.290265814143, 102956.0, 110043.0, 112639.43680281546, 112912.43543782392, 113703.43148284206, 64411.67794160999, 74525.0, 84452.0, 99043.0, 100770.49614752026, 69724.0, 90477.54761226152, 90685.09314906898, 91817.54091229501, 92295.07704922999, 10363.896361036595, 10661.147108231478, 10695.786084278427, 10747.570097196025, 10800.351978880977, 46839.29741053819, 47208.055838883716, 47658.52341476541, 49055.26417103818, 49948.0, 69670.0, 73975.0, 74115.62942185254, 74153.25846741463, 74316.62841685757, 13483.595492135251, 13534.255615941218, 13542.05205635596, 13643.727125457634, 13755.10591811529, 15119.244037798286, 15237.923810380877, 15248.923755381153, 15855.68288634244, 15986.760198596798, 14806.481773137888, 15070.321835517647, 15357.232138392812, 15764.211789410254, 16203.75694364609, 13542.322883855571, 13543.322833858465, 13550.32248387557, 13866.653333666336, 14043.15741055499, 12832.165909215872, 12962.481500739865, 12962.935185324013, 13108.803367949298, 13580.728385432434, 12205.816912746139, 12219.69450763738, 12441.75116497647, 12716.809247861474, 12821.487140514648, 12324.383780811102, 12357.443915023665, 12467.87532124667, 12574.434150463434, 12581.245125292511], "value": 8015.817341162271, "unit": "score"}], "name": "dromaeo_css", "value": 7647.948772237096, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 103.0, 109.0, 109.0, 109.0, 110.0, 108.0, 109.0, 109.0, 108.0], "value": 108.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 130.0, 126.0, 167.0, 126.0, 125.0, 155.0, 129.0, 151.0, 130.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 159.0, 160.0, 160.0, 159.0, 161.0, 160.0, 163.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 128.0, 76.0, 75.0, 73.0, 75.0, 100.0, 74.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 113.0, 83.0, 83.0, 86.0, 117.0, 84.0, 83.0, 140.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 118.0, 116.0, 118.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 51.0, 50.0, 51.0, 51.0, 51.0, 51.0, 50.0, 54.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 79.0, 76.0, 77.0, 77.0, 78.0, 77.0, 101.0, 77.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 125.0, 116.0, 116.0, 119.0, 122.0, 125.0, 127.0, 140.0, 130.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 155.0, 155.0, 157.0, 156.0, 161.0, 155.0, 156.0, 160.0, 155.0], "value": 157.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 61.0, 62.0, 62.0, 62.0, 60.0, 60.0, 63.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1479.3, "alertThreshold": 2.0}]} 09:47:01 INFO - Return code: 0 09:47:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:01 ERROR - # TBPL SUCCESS # 09:47:01 INFO - Running post-action listener: _resource_record_post_action 09:47:01 INFO - ##### 09:47:01 INFO - ##### Finished run-tests step (success) 09:47:01 INFO - ##### 09:47:01 INFO - Running post-run listener: _resource_record_post_run 09:47:01 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 266240; Write bytes: 366669824; Read time: 28; Write time: 227772 09:47:01 INFO - TinderboxPrint: CPU usage
13.1% 09:47:01 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 09:47:01 INFO - TinderboxPrint: I/O write bytes / time
366,669,824 / 227,772 09:47:01 INFO - TinderboxPrint: CPU idle
8,247.3 (86.9%) 09:47:01 INFO - TinderboxPrint: CPU user
1,200.1 (12.6%) 09:47:01 INFO - TinderboxPrint: Swap in / out
0 / 0 09:47:01 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 77271040; Read time: 0; Write time: 44868 09:47:01 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 266240; Write bytes: 254435328; Read time: 28; Write time: 138480 09:47:01 INFO - Running post-run listener: _upload_blobber_files 09:47:01 INFO - Blob upload gear active. 09:47:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:01 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 09:47:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:47:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:47:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:47:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:02 INFO - (blobuploader) - INFO - Done attempting. 09:47:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:47:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:47:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:03 INFO - (blobuploader) - INFO - Done attempting. 09:47:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:07 INFO - (blobuploader) - INFO - Done attempting. 09:47:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:07 INFO - Return code: 0 09:47:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a67987b91869756b0ad4c53180dc3fb0c64ea2d17349d107f7f3df7806014bca3d108ebb690a0a35509c581bb06a77816cf04df47a4abfa5c75383331dcc245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28522e7edc97bc0878f51b1c733896842f4a424cfdba7d912d2fad773594b36211410413185f8b034c4985bd69f55977ae6c39b4a95b9d113d427214f64eab7b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c1d78057cfc1df7bfeaa92ef5c4aa41e9ed066845a64e6732c21d27763749d52a4916ecfe748c3a3d59b3987fb273ddaefab3e18c86971576f85a55b85d6c154"} 09:47:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:07 INFO - Contents: 09:47:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a67987b91869756b0ad4c53180dc3fb0c64ea2d17349d107f7f3df7806014bca3d108ebb690a0a35509c581bb06a77816cf04df47a4abfa5c75383331dcc245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28522e7edc97bc0878f51b1c733896842f4a424cfdba7d912d2fad773594b36211410413185f8b034c4985bd69f55977ae6c39b4a95b9d113d427214f64eab7b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c1d78057cfc1df7bfeaa92ef5c4aa41e9ed066845a64e6732c21d27763749d52a4916ecfe748c3a3d59b3987fb273ddaefab3e18c86971576f85a55b85d6c154"} 09:47:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:07 INFO - Copying logs to upload dir... 09:47:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.102728 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-21 09:47:07.369010) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 09:47:07.373081) ========= 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=0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a67987b91869756b0ad4c53180dc3fb0c64ea2d17349d107f7f3df7806014bca3d108ebb690a0a35509c581bb06a77816cf04df47a4abfa5c75383331dcc245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28522e7edc97bc0878f51b1c733896842f4a424cfdba7d912d2fad773594b36211410413185f8b034c4985bd69f55977ae6c39b4a95b9d113d427214f64eab7b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c1d78057cfc1df7bfeaa92ef5c4aa41e9ed066845a64e6732c21d27763749d52a4916ecfe748c3a3d59b3987fb273ddaefab3e18c86971576f85a55b85d6c154"} build_url:https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/fazuaqNIQKaj38CXVWQaDg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a67987b91869756b0ad4c53180dc3fb0c64ea2d17349d107f7f3df7806014bca3d108ebb690a0a35509c581bb06a77816cf04df47a4abfa5c75383331dcc245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28522e7edc97bc0878f51b1c733896842f4a424cfdba7d912d2fad773594b36211410413185f8b034c4985bd69f55977ae6c39b4a95b9d113d427214f64eab7b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c1d78057cfc1df7bfeaa92ef5c4aa41e9ed066845a64e6732c21d27763749d52a4916ecfe748c3a3d59b3987fb273ddaefab3e18c86971576f85a55b85d6c154"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 09:47:07.401351) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:47:07.401947) ========= 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=0333a256a5e4f052382dd180000001b1-1466525890.948651-697199900 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004383 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 09:47:07.486352) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 09:47:07.486913) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 09:47:07.487425) ========= ========= Total master_lag: 0.17 =========