builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1469529050.28 results: success (0) buildid: 20160726004006 builduid: fbddfa4bd0b848a2bb99e24344db49b2 revision: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:50.280780) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:50.281324) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:50.281596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:50.292798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:50.293123) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-26 03:30:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2016-07-26 03:30:51 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117180 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:50.422074) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:50.422384) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021630 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:50.473464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 03:30:50.473781) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 --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 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 --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=516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-26 03:30:51,586 truncating revision to first 12 chars 2016-07-26 03:30:51,586 Setting DEBUG logging. 2016-07-26 03:30:51,586 attempt 1/10 2016-07-26 03:30:51,586 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/517663b29d7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-26 03:30:52,634 unpacking tar archive at: mozilla-aurora-517663b29d7f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.399253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-26 03:30:51.888258) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:51.888548) ========= script_repo_revision: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:51.888896) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:51.889174) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-26 03:30:51.899294) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 6 secs) (at 2016-07-26 03:30:51.899555) ========= /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=516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:30:53 INFO - MultiFileLogger online at 20160726 03:30:53 in /builds/slave/test 03:30:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:30:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:30:53 INFO - {'append_to_log': False, 03:30:53 INFO - 'base_work_dir': '/builds/slave/test', 03:30:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:30:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:30:53 INFO - 'branch': 'Mozilla-Aurora', 03:30:53 INFO - 'buildbot_json_path': 'buildprops.json', 03:30:53 INFO - 'config_files': ('talos/linux_config.py',), 03:30:53 INFO - 'default_actions': ('clobber', 03:30:53 INFO - 'read-buildbot-config', 03:30:53 INFO - 'download-and-extract', 03:30:53 INFO - 'populate-webroot', 03:30:53 INFO - 'create-virtualenv', 03:30:53 INFO - 'install', 03:30:53 INFO - 'run-tests'), 03:30:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:30:53 INFO - 'download_minidump_stackwalk': True, 03:30:53 INFO - 'download_symbols': 'ondemand', 03:30:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:30:53 INFO - 'tooltool.py': '/tools/tooltool.py', 03:30:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:30:53 INFO - '/tools/misc-python/virtualenv.py')}, 03:30:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:30:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:30:53 INFO - 'installer_path': 'installer.exe', 03:30:53 INFO - 'log_level': 'info', 03:30:53 INFO - 'log_name': 'talos', 03:30:53 INFO - 'log_to_console': True, 03:30:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:30:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:30:53 INFO - 'opt_config_files': (), 03:30:53 INFO - 'pip_index': False, 03:30:53 INFO - 'sps_profile': False, 03:30:53 INFO - 'sps_profile_interval': 0, 03:30:53 INFO - 'suite': 'dromaeojs', 03:30:53 INFO - 'system_bits': '32', 03:30:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:30:53 INFO - 'title': 'talos-linux64-ix-061', 03:30:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:30:53 INFO - 'use_talos_json': True, 03:30:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:30:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:30:53 INFO - 'work_dir': 'build'} 03:30:53 INFO - ##### 03:30:53 INFO - ##### Running clobber step. 03:30:53 INFO - ##### 03:30:53 INFO - Running pre-action listener: _resource_record_pre_action 03:30:53 INFO - Running main action method: clobber 03:30:53 INFO - rmtree: /builds/slave/test/build 03:30:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:30:53 INFO - Running post-action listener: _resource_record_post_action 03:30:53 INFO - ##### 03:30:53 INFO - ##### Finished clobber step (success) 03:30:53 INFO - ##### 03:30:53 INFO - ##### 03:30:53 INFO - ##### Running read-buildbot-config step. 03:30:53 INFO - ##### 03:30:53 INFO - Running pre-action listener: _resource_record_pre_action 03:30:53 INFO - Running main action method: read_buildbot_config 03:30:53 INFO - Using buildbot properties: 03:30:53 INFO - { 03:30:53 INFO - "project": "", 03:30:53 INFO - "product": "firefox", 03:30:53 INFO - "script_repo_revision": "production", 03:30:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:30:53 INFO - "repository": "", 03:30:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:30:53 INFO - "buildid": "20160726004006", 03:30:53 INFO - "stage_platform": "linux64-pgo", 03:30:53 INFO - "basedir": "/builds/slave/test", 03:30:53 INFO - "nightly_build": "True", 03:30:53 INFO - "buildnumber": 424, 03:30:53 INFO - "slavename": "talos-linux64-ix-061", 03:30:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:30:53 INFO - "platform": "ubuntu64_hw", 03:30:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:30:53 INFO - "branch": "mozilla-aurora", 03:30:53 INFO - "repo_path": "releases/mozilla-aurora", 03:30:53 INFO - "revision": "517663b29d7ffc2ce91f90dc7beaac4fa35e52e5", 03:30:53 INFO - "pgo_build": "True", 03:30:53 INFO - "builduid": "fbddfa4bd0b848a2bb99e24344db49b2", 03:30:53 INFO - "slavebuilddir": "test" 03:30:53 INFO - } 03:30:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:30:53 INFO - Running post-action listener: _resource_record_post_action 03:30:53 INFO - ##### 03:30:53 INFO - ##### Finished read-buildbot-config step (success) 03:30:53 INFO - ##### 03:30:53 INFO - ##### 03:30:53 INFO - ##### Running download-and-extract step. 03:30:53 INFO - ##### 03:30:53 INFO - Running pre-action listener: _resource_record_pre_action 03:30:53 INFO - Running main action method: download_and_extract 03:30:53 INFO - mkdir: /builds/slave/test/build/tests 03:30: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')]} 03:30:53 INFO - https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:30:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:30:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:30:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:30:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:30:56 INFO - Downloaded 1361 bytes. 03:30:56 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:30:56 INFO - Using the following test package requirements: 03:30:56 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:30:56 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:56 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:30:56 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:56 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:30:56 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:56 INFO - u'jsshell-linux-x86_64.zip'], 03:30:56 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:56 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:30:56 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:30:56 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:56 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:30:56 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:56 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:30:56 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:56 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:30:56 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:30:56 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:30:56 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:30: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')]} 03:30:56 INFO - https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:30:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:30:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:30:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:30:59 INFO - Downloaded 24494756 bytes. 03:30:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:30:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:31:01 INFO - Return code: 0 03:31:01 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:01 INFO - https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:31:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:31:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:31:02 INFO - Downloaded 24494756 bytes. 03:31:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:31:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:31:04 INFO - Return code: 0 03:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:04 INFO - https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:31:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:31:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:31:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:31:05 INFO - Downloaded 10833369 bytes. 03:31:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:31:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:31:05 INFO - Return code: 0 03:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:05 INFO - https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:31:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:31:06 INFO - Downloaded 63637256 bytes. 03:31:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:06 INFO - mkdir: /builds/slave/test/properties 03:31:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:31:06 INFO - Writing to file /builds/slave/test/properties/build_url 03:31:06 INFO - Contents: 03:31:06 INFO - build_url:https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:31:07 INFO - Running post-action listener: _resource_record_post_action 03:31:07 INFO - Running post-action listener: set_extra_try_arguments 03:31:07 INFO - ##### 03:31:07 INFO - ##### Finished download-and-extract step (success) 03:31:07 INFO - ##### 03:31:07 INFO - ##### 03:31:07 INFO - ##### Running populate-webroot step. 03:31:07 INFO - ##### 03:31:07 INFO - Running pre-action listener: _resource_record_pre_action 03:31:07 INFO - Running main action method: populate_webroot 03:31:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:31:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:31:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:31:07 INFO - '--tppagecycles', 03:31:07 INFO - '7'], 03:31:07 INFO - 'tests': ['tsvgm']}}, 03:31:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:31:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:31:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:31:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:31:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:07 INFO - 'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:31:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:07 INFO - 'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['damp', 'tps']}, 03:31:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:07 INFO - 'tests': ['damp', 'tps']}, 03:31:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['dromaeo_dom']}, 03:31:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:31:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['a11yr', 03:31:07 INFO - 'ts_paint', 03:31:07 INFO - 'tpaint', 03:31:07 INFO - 'sessionrestore', 03:31:07 INFO - 'sessionrestore_no_auto_restore', 03:31:07 INFO - 'tabpaint']}, 03:31:07 INFO - 'other-e10s': {'tests': ['a11yr', 03:31:07 INFO - 'ts_paint', 03:31:07 INFO - 'tpaint', 03:31:07 INFO - 'sessionrestore', 03:31:07 INFO - 'sessionrestore_no_auto_restore', 03:31:07 INFO - 'tabpaint']}, 03:31:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:31:07 INFO - 'ts_paint', 03:31:07 INFO - 'tpaint', 03:31:07 INFO - 'sessionrestore', 03:31:07 INFO - 'sessionrestore_no_auto_restore', 03:31:07 INFO - 'tabpaint']}, 03:31:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:31:07 INFO - 'ts_paint', 03:31:07 INFO - 'tpaint', 03:31:07 INFO - 'sessionrestore', 03:31:07 INFO - 'sessionrestore_no_auto_restore', 03:31:07 INFO - 'tabpaint']}, 03:31:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['a11yr', 03:31:07 INFO - 'ts_paint', 03:31:07 INFO - 'tpaint', 03:31:07 INFO - 'sessionrestore', 03:31:07 INFO - 'sessionrestore_no_auto_restore', 03:31:07 INFO - 'tabpaint']}, 03:31:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['a11yr', 03:31:07 INFO - 'ts_paint', 03:31:07 INFO - 'tpaint', 03:31:07 INFO - 'sessionrestore', 03:31:07 INFO - 'sessionrestore_no_auto_restore', 03:31:07 INFO - 'tabpaint']}, 03:31:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['tsvgx', 03:31:07 INFO - 'tsvgr_opacity', 03:31:07 INFO - 'tart', 03:31:07 INFO - 'tscrollx', 03:31:07 INFO - 'cart']}, 03:31:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:31:07 INFO - 'tsvgr_opacity', 03:31:07 INFO - 'tart', 03:31:07 INFO - 'tscrollx', 03:31:07 INFO - 'cart']}, 03:31:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:07 INFO - 'talos_options': ['--disable-e10s'], 03:31:07 INFO - 'tests': ['tp5o']}, 03:31:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:07 INFO - 'tests': ['tp5o']}, 03:31:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:07 INFO - 'talos_options': ['--disable-e10s', 03:31:07 INFO - '--xperf_path', 03:31:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:07 INFO - 'tests': ['tp5n']}, 03:31:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:31:07 INFO - 'talos_options': ['--xperf_path', 03:31:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:31:07 INFO - 'tests': ['tp5n']}}, 03:31:07 INFO - 'talos.zip': {'path': '', 03:31:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:31:07 INFO - Running post-action listener: _resource_record_post_action 03:31:07 INFO - ##### 03:31:07 INFO - ##### Finished populate-webroot step (success) 03:31:07 INFO - ##### 03:31:07 INFO - ##### 03:31:07 INFO - ##### Running create-virtualenv step. 03:31:07 INFO - ##### 03:31:07 INFO - Running pre-action listener: _resource_record_pre_action 03:31:07 INFO - Running main action method: create_virtualenv 03:31:07 INFO - Creating virtualenv /builds/slave/test/build/venv 03:31:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:31:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:31:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:31:07 INFO - Using real prefix '/usr' 03:31:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:31:08 INFO - Installing distribute.............................................................................................................................................................................................done. 03:31:10 INFO - Installing pip.................done. 03:31:10 INFO - Return code: 0 03:31:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:31:10 INFO - Using env: {'DISPLAY': ':0', 03:31:10 INFO - 'HOME': '/home/cltbld', 03:31:10 INFO - 'LANG': 'en_US.UTF-8', 03:31:10 INFO - 'LANGUAGE': 'en_US:en', 03:31:10 INFO - 'LOGNAME': 'cltbld', 03:31:10 INFO - 'MAIL': '/var/mail/cltbld', 03:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:10 INFO - 'MOZ_NO_REMOTE': '1', 03:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:10 INFO - 'NO_EM_RESTART': '1', 03:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:10 INFO - 'PWD': '/builds/slave/test', 03:31:10 INFO - 'SHELL': '/bin/bash', 03:31:10 INFO - 'SHLVL': '1', 03:31:10 INFO - 'TERM': 'linux', 03:31:10 INFO - 'TMOUT': '86400', 03:31:10 INFO - 'USER': 'cltbld', 03:31:10 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:10 INFO - '_': '/tools/buildbot/bin/python'} 03:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:31:10 INFO - Cleaning up... 03:31:10 INFO - Return code: 0 03:31:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:31:10 INFO - Using env: {'DISPLAY': ':0', 03:31:10 INFO - 'HOME': '/home/cltbld', 03:31:10 INFO - 'LANG': 'en_US.UTF-8', 03:31:10 INFO - 'LANGUAGE': 'en_US:en', 03:31:10 INFO - 'LOGNAME': 'cltbld', 03:31:10 INFO - 'MAIL': '/var/mail/cltbld', 03:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:10 INFO - 'MOZ_NO_REMOTE': '1', 03:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:10 INFO - 'NO_EM_RESTART': '1', 03:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:10 INFO - 'PWD': '/builds/slave/test', 03:31:10 INFO - 'SHELL': '/bin/bash', 03:31:10 INFO - 'SHLVL': '1', 03:31:10 INFO - 'TERM': 'linux', 03:31:10 INFO - 'TMOUT': '86400', 03:31:10 INFO - 'USER': 'cltbld', 03:31:10 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:10 INFO - '_': '/tools/buildbot/bin/python'} 03:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:10 INFO - Downloading/unpacking psutil>=3.1.1 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31: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 03:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:31:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:12 INFO - Installing collected packages: psutil 03:31:12 INFO - Running setup.py install for psutil 03:31:12 INFO - building 'psutil._psutil_linux' extension 03:31:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:31:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:31:12 INFO - building 'psutil._psutil_posix' extension 03:31:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:31:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:31:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:31:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:31:13 INFO - Successfully installed psutil 03:31:13 INFO - Cleaning up... 03:31:13 INFO - Return code: 0 03:31:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:31:13 INFO - Using env: {'DISPLAY': ':0', 03:31:13 INFO - 'HOME': '/home/cltbld', 03:31:13 INFO - 'LANG': 'en_US.UTF-8', 03:31:13 INFO - 'LANGUAGE': 'en_US:en', 03:31:13 INFO - 'LOGNAME': 'cltbld', 03:31:13 INFO - 'MAIL': '/var/mail/cltbld', 03:31:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:13 INFO - 'MOZ_NO_REMOTE': '1', 03:31:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:13 INFO - 'NO_EM_RESTART': '1', 03:31:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:13 INFO - 'PWD': '/builds/slave/test', 03:31:13 INFO - 'SHELL': '/bin/bash', 03:31:13 INFO - 'SHLVL': '1', 03:31:13 INFO - 'TERM': 'linux', 03:31:13 INFO - 'TMOUT': '86400', 03:31:13 INFO - 'USER': 'cltbld', 03:31:13 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:13 INFO - '_': '/tools/buildbot/bin/python'} 03:31:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:31:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:31:15 INFO - Installing collected packages: mozsystemmonitor 03:31:15 INFO - Running setup.py install for mozsystemmonitor 03:31:15 INFO - Successfully installed mozsystemmonitor 03:31:15 INFO - Cleaning up... 03:31:15 INFO - Return code: 0 03:31:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:15 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 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:31:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:31:15 INFO - Using env: {'DISPLAY': ':0', 03:31:15 INFO - 'HOME': '/home/cltbld', 03:31:15 INFO - 'LANG': 'en_US.UTF-8', 03:31:15 INFO - 'LANGUAGE': 'en_US:en', 03:31:15 INFO - 'LOGNAME': 'cltbld', 03:31:15 INFO - 'MAIL': '/var/mail/cltbld', 03:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:15 INFO - 'MOZ_NO_REMOTE': '1', 03:31:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:15 INFO - 'NO_EM_RESTART': '1', 03:31:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:15 INFO - 'PWD': '/builds/slave/test', 03:31:15 INFO - 'SHELL': '/bin/bash', 03:31:15 INFO - 'SHLVL': '1', 03:31:15 INFO - 'TERM': 'linux', 03:31:15 INFO - 'TMOUT': '86400', 03:31:15 INFO - 'USER': 'cltbld', 03:31:15 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:15 INFO - '_': '/tools/buildbot/bin/python'} 03:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:15 INFO - Downloading/unpacking jsonschema==2.5.1 03:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:31:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:17 INFO - Downloading functools32-3.2.3-2.tar.gz 03:31:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:31:17 INFO - warning: no files found matching '*.txt' 03:31:17 INFO - no previously-included directories found matching 'build' 03:31:17 INFO - no previously-included directories found matching 'dist' 03:31:17 INFO - no previously-included directories found matching '.git*' 03:31:17 INFO - Installing collected packages: jsonschema, functools32 03:31:17 INFO - Running setup.py install for functools32 03:31:18 INFO - warning: no files found matching '*.txt' 03:31:18 INFO - no previously-included directories found matching 'build' 03:31:18 INFO - no previously-included directories found matching 'dist' 03:31:18 INFO - no previously-included directories found matching '.git*' 03:31:18 INFO - Successfully installed jsonschema functools32 03:31:18 INFO - Cleaning up... 03:31:18 INFO - Return code: 0 03:31:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:31:18 INFO - Using env: {'DISPLAY': ':0', 03:31:18 INFO - 'HOME': '/home/cltbld', 03:31:18 INFO - 'LANG': 'en_US.UTF-8', 03:31:18 INFO - 'LANGUAGE': 'en_US:en', 03:31:18 INFO - 'LOGNAME': 'cltbld', 03:31:18 INFO - 'MAIL': '/var/mail/cltbld', 03:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:18 INFO - 'MOZ_NO_REMOTE': '1', 03:31:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:18 INFO - 'NO_EM_RESTART': '1', 03:31:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:18 INFO - 'PWD': '/builds/slave/test', 03:31:18 INFO - 'SHELL': '/bin/bash', 03:31:18 INFO - 'SHLVL': '1', 03:31:18 INFO - 'TERM': 'linux', 03:31:18 INFO - 'TMOUT': '86400', 03:31:18 INFO - 'USER': 'cltbld', 03:31:18 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:18 INFO - '_': '/tools/buildbot/bin/python'} 03:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:18 INFO - Downloading/unpacking blobuploader==1.2.4 03:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:20 INFO - Downloading blobuploader-1.2.4.tar.gz 03:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:31:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:31:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:20 INFO - Downloading docopt-0.6.1.tar.gz 03:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:31:20 INFO - Installing collected packages: blobuploader, requests, docopt 03:31:20 INFO - Running setup.py install for blobuploader 03:31:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:31:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:31:21 INFO - Running setup.py install for requests 03:31:21 INFO - Running setup.py install for docopt 03:31:21 INFO - Successfully installed blobuploader requests docopt 03:31:21 INFO - Cleaning up... 03:31:21 INFO - Return code: 0 03:31:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:21 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 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:31:21 INFO - Using env: {'DISPLAY': ':0', 03:31:21 INFO - 'HOME': '/home/cltbld', 03:31:21 INFO - 'LANG': 'en_US.UTF-8', 03:31:21 INFO - 'LANGUAGE': 'en_US:en', 03:31:21 INFO - 'LOGNAME': 'cltbld', 03:31:21 INFO - 'MAIL': '/var/mail/cltbld', 03:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:21 INFO - 'MOZ_NO_REMOTE': '1', 03:31:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:21 INFO - 'NO_EM_RESTART': '1', 03:31:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:21 INFO - 'PWD': '/builds/slave/test', 03:31:21 INFO - 'SHELL': '/bin/bash', 03:31:21 INFO - 'SHLVL': '1', 03:31:21 INFO - 'TERM': 'linux', 03:31:21 INFO - 'TMOUT': '86400', 03:31:21 INFO - 'USER': 'cltbld', 03:31:21 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:21 INFO - '_': '/tools/buildbot/bin/python'} 03:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:21 INFO - Running setup.py (path:/tmp/pip-VIB0A9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:21 INFO - Running setup.py (path:/tmp/pip-AerhTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:21 INFO - Running setup.py (path:/tmp/pip-hy5sqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:22 INFO - Running setup.py (path:/tmp/pip-Tcy6_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:22 INFO - Running setup.py (path:/tmp/pip-rzhMsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:22 INFO - Running setup.py (path:/tmp/pip-hWdMF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:22 INFO - Running setup.py (path:/tmp/pip-0agkQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:22 INFO - Running setup.py (path:/tmp/pip-TEplO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:22 INFO - Running setup.py (path:/tmp/pip-IlM4FM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:22 INFO - Running setup.py (path:/tmp/pip-LHduvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:22 INFO - Running setup.py (path:/tmp/pip-rOL6Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:22 INFO - Running setup.py (path:/tmp/pip-NUMvfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:22 INFO - Running setup.py (path:/tmp/pip-kSYOfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:22 INFO - Running setup.py (path:/tmp/pip-QRSluM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:22 INFO - Running setup.py (path:/tmp/pip-1PDh2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:22 INFO - Running setup.py (path:/tmp/pip-3naOQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:22 INFO - Running setup.py (path:/tmp/pip-KpcQJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:31:22 INFO - Running setup.py install for manifestparser 03:31:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Running setup.py install for mozcrash 03:31:23 INFO - Running setup.py install for mozdebug 03:31:23 INFO - Running setup.py install for mozdevice 03:31:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Running setup.py install for mozfile 03:31:23 INFO - Running setup.py install for mozhttpd 03:31:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Running setup.py install for mozinfo 03:31:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Running setup.py install for mozInstall 03:31:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:31:23 INFO - Running setup.py install for mozleak 03:31:23 INFO - Running setup.py install for mozlog 03:31:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:31:24 INFO - Running setup.py install for moznetwork 03:31:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:31:24 INFO - Running setup.py install for mozprocess 03:31:24 INFO - Running setup.py install for mozprofile 03:31:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:31:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:31:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:31:24 INFO - Running setup.py install for mozrunner 03:31:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:31:24 INFO - Running setup.py install for mozscreenshot 03:31:24 INFO - Running setup.py install for moztest 03:31:24 INFO - Running setup.py install for mozversion 03:31:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:31:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:31:24 INFO - Cleaning up... 03:31:24 INFO - Return code: 0 03:31:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:24 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 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:31:24 INFO - Using env: {'DISPLAY': ':0', 03:31:24 INFO - 'HOME': '/home/cltbld', 03:31:24 INFO - 'LANG': 'en_US.UTF-8', 03:31:24 INFO - 'LANGUAGE': 'en_US:en', 03:31:24 INFO - 'LOGNAME': 'cltbld', 03:31:24 INFO - 'MAIL': '/var/mail/cltbld', 03:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:24 INFO - 'MOZ_NO_REMOTE': '1', 03:31:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:24 INFO - 'NO_EM_RESTART': '1', 03:31:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:24 INFO - 'PWD': '/builds/slave/test', 03:31:24 INFO - 'SHELL': '/bin/bash', 03:31:24 INFO - 'SHLVL': '1', 03:31:24 INFO - 'TERM': 'linux', 03:31:24 INFO - 'TMOUT': '86400', 03:31:24 INFO - 'USER': 'cltbld', 03:31:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:24 INFO - '_': '/tools/buildbot/bin/python'} 03:31:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:31:25 INFO - Running setup.py (path:/tmp/pip-NkIsT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:31:25 INFO - Running setup.py (path:/tmp/pip-PqRyzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:31:25 INFO - Running setup.py (path:/tmp/pip-vaOIOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:31:25 INFO - Running setup.py (path:/tmp/pip-QMu88U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:31:25 INFO - Running setup.py (path:/tmp/pip-gUBBnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:31:25 INFO - Running setup.py (path:/tmp/pip-M_WEQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:31:25 INFO - Running setup.py (path:/tmp/pip-JAcBNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:31:25 INFO - Running setup.py (path:/tmp/pip-d8sxFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:31:25 INFO - Running setup.py (path:/tmp/pip-wgZtPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:31:25 INFO - Running setup.py (path:/tmp/pip-kGc57X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:31:25 INFO - Running setup.py (path:/tmp/pip-yVsrXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:31:25 INFO - Running setup.py (path:/tmp/pip-jYLrax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:31:25 INFO - Running setup.py (path:/tmp/pip-25xh67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:31:25 INFO - Running setup.py (path:/tmp/pip-nKY7tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:26 INFO - Running setup.py (path:/tmp/pip-B4aHxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:31:26 INFO - Running setup.py (path:/tmp/pip-PJ1BHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:31:26 INFO - Running setup.py (path:/tmp/pip-DaSmya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:31:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:31:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:28 INFO - Downloading blessings-1.6.tar.gz 03:31:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:31:28 INFO - Installing collected packages: blessings 03:31:28 INFO - Running setup.py install for blessings 03:31:28 INFO - Successfully installed blessings 03:31:28 INFO - Cleaning up... 03:31:28 INFO - Return code: 0 03:31:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:31:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:28 INFO - Reading from file tmpfile_stdout 03:31:28 INFO - Current package versions: 03:31:28 INFO - argparse == 1.2.1 03:31:28 INFO - blessings == 1.6 03:31:28 INFO - blobuploader == 1.2.4 03:31:28 INFO - docopt == 0.6.1 03:31:28 INFO - functools32 == 3.2.3-2 03:31:28 INFO - jsonschema == 2.5.1 03:31:28 INFO - manifestparser == 1.1 03:31:28 INFO - mozInstall == 1.12 03:31:28 INFO - mozcrash == 0.17 03:31:28 INFO - mozdebug == 0.1 03:31:28 INFO - mozdevice == 0.48 03:31:28 INFO - mozfile == 1.2 03:31:28 INFO - mozhttpd == 0.7 03:31:28 INFO - mozinfo == 0.9 03:31:28 INFO - mozleak == 0.1 03:31:28 INFO - mozlog == 3.2 03:31:28 INFO - moznetwork == 0.27 03:31:28 INFO - mozprocess == 0.23 03:31:28 INFO - mozprofile == 0.28 03:31:28 INFO - mozrunner == 6.12 03:31:28 INFO - mozscreenshot == 0.1 03:31:28 INFO - mozsystemmonitor == 0.3 03:31:28 INFO - moztest == 0.7 03:31:28 INFO - mozversion == 1.4 03:31:28 INFO - psutil == 3.1.1 03:31:28 INFO - requests == 1.2.3 03:31:28 INFO - wsgiref == 0.1.2 03:31:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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')]} 03:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:28 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 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:31:28 INFO - Using env: {'DISPLAY': ':0', 03:31:28 INFO - 'HOME': '/home/cltbld', 03:31:28 INFO - 'LANG': 'en_US.UTF-8', 03:31:28 INFO - 'LANGUAGE': 'en_US:en', 03:31:28 INFO - 'LOGNAME': 'cltbld', 03:31:28 INFO - 'MAIL': '/var/mail/cltbld', 03:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:28 INFO - 'MOZ_NO_REMOTE': '1', 03:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:28 INFO - 'NO_EM_RESTART': '1', 03:31:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:28 INFO - 'PWD': '/builds/slave/test', 03:31:28 INFO - 'SHELL': '/bin/bash', 03:31:28 INFO - 'SHLVL': '1', 03:31:28 INFO - 'TERM': 'linux', 03:31:28 INFO - 'TMOUT': '86400', 03:31:28 INFO - 'USER': 'cltbld', 03:31:28 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:28 INFO - '_': '/tools/buildbot/bin/python'} 03:31:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:31:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:31:28 INFO - Cleaning up... 03:31:28 INFO - Return code: 0 03:31:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:31: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')]} 03:31:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31: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')]} 03:31:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:28 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 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:31:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:31:28 INFO - Using env: {'DISPLAY': ':0', 03:31:28 INFO - 'HOME': '/home/cltbld', 03:31:28 INFO - 'LANG': 'en_US.UTF-8', 03:31:28 INFO - 'LANGUAGE': 'en_US:en', 03:31:28 INFO - 'LOGNAME': 'cltbld', 03:31:28 INFO - 'MAIL': '/var/mail/cltbld', 03:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:28 INFO - 'MOZ_NO_REMOTE': '1', 03:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:28 INFO - 'NO_EM_RESTART': '1', 03:31:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:28 INFO - 'PWD': '/builds/slave/test', 03:31:28 INFO - 'SHELL': '/bin/bash', 03:31:28 INFO - 'SHLVL': '1', 03:31:28 INFO - 'TERM': 'linux', 03:31:28 INFO - 'TMOUT': '86400', 03:31:28 INFO - 'USER': 'cltbld', 03:31:28 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:28 INFO - '_': '/tools/buildbot/bin/python'} 03:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:31:29 INFO - Cleaning up... 03:31:29 INFO - Return code: 0 03:31:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:31:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:31:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:31:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:31:29 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 0x7f1cb815af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24ea2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, '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': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:31:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:31:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:31:29 INFO - Using env: {'DISPLAY': ':0', 03:31:29 INFO - 'HOME': '/home/cltbld', 03:31:29 INFO - 'LANG': 'en_US.UTF-8', 03:31:29 INFO - 'LANGUAGE': 'en_US:en', 03:31:29 INFO - 'LOGNAME': 'cltbld', 03:31:29 INFO - 'MAIL': '/var/mail/cltbld', 03:31:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:29 INFO - 'MOZ_NO_REMOTE': '1', 03:31:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:29 INFO - 'NO_EM_RESTART': '1', 03:31:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:29 INFO - 'PWD': '/builds/slave/test', 03:31:29 INFO - 'SHELL': '/bin/bash', 03:31:29 INFO - 'SHLVL': '1', 03:31:29 INFO - 'TERM': 'linux', 03:31:29 INFO - 'TMOUT': '86400', 03:31:29 INFO - 'USER': 'cltbld', 03:31:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:29 INFO - '_': '/tools/buildbot/bin/python'} 03:31:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:31:29 INFO - Downloading/unpacking flake8 03:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:31:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:31:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:31 INFO - no previously-included directories found matching 'docs/_build' 03:31:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:31:31 INFO - Running setup.py install for pep8 03:31:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:31:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:31:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:31:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:31:32 INFO - no previously-included directories found matching 'docs/_build' 03:31:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:31:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:31:32 INFO - Cleaning up... 03:31:32 INFO - Return code: 0 03:31:32 INFO - Running post-action listener: _resource_record_post_action 03:31:32 INFO - Running post-action listener: _start_resource_monitoring 03:31:32 INFO - Starting resource monitoring. 03:31:32 INFO - ##### 03:31:32 INFO - ##### Finished create-virtualenv step (success) 03:31:32 INFO - ##### 03:31:32 INFO - ##### 03:31:32 INFO - ##### Running install step. 03:31:32 INFO - ##### 03:31:32 INFO - Running pre-action listener: _resource_record_pre_action 03:31:32 INFO - Running main action method: install 03:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:31:32 INFO - Reading from file tmpfile_stdout 03:31:32 INFO - Detecting whether we're running mozinstall >=1.0... 03:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:31:32 INFO - Reading from file tmpfile_stdout 03:31:32 INFO - Output received: 03:31:32 INFO - Usage: mozinstall [options] installer 03:31:32 INFO - Options: 03:31:32 INFO - -h, --help show this help message and exit 03:31:32 INFO - -d DEST, --destination=DEST 03:31:32 INFO - Directory to install application into. [default: 03:31:32 INFO - "/builds/slave/test"] 03:31:32 INFO - --app=APP Application being installed. [default: firefox] 03:31:32 INFO - mkdir: /builds/slave/test/build/application 03:31:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:31:48 INFO - Reading from file tmpfile_stdout 03:31:48 INFO - Output received: 03:31:48 INFO - /builds/slave/test/build/application/firefox/firefox 03:31:48 INFO - Running post-action listener: _resource_record_post_action 03:31:48 INFO - ##### 03:31:48 INFO - ##### Finished install step (success) 03:31:48 INFO - ##### 03:31:48 INFO - ##### 03:31:48 INFO - ##### Running run-tests step. 03:31:48 INFO - ##### 03:31:48 INFO - Running pre-action listener: _resource_record_pre_action 03:31:48 INFO - Running main action method: run_tests 03:31:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:31:48 INFO - Python 2.7.3 03:31:48 INFO - Return code: 0 03:31:48 INFO - grabbing minidump binary from tooltool 03:31: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')]} 03:31:48 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 0x24e4e20>, '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 0x24e83a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e8830>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:31:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:31:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:31:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:31:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:31:48 INFO - Return code: 0 03:31:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:31:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:31:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:31:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:31:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:31:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:31:49 INFO - Return code: 0 03:31:49 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/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:31:49 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/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:31:49 INFO - Using env: {'DISPLAY': ':0', 03:31:49 INFO - 'HOME': '/home/cltbld', 03:31:49 INFO - 'LANG': 'en_US.UTF-8', 03:31:49 INFO - 'LANGUAGE': 'en_US:en', 03:31:49 INFO - 'LOGNAME': 'cltbld', 03:31:49 INFO - 'MAIL': '/var/mail/cltbld', 03:31:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:31:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:31:49 INFO - 'MOZ_NO_REMOTE': '1', 03:31:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:31:49 INFO - 'NO_EM_RESTART': '1', 03:31:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:31:49 INFO - 'PWD': '/builds/slave/test', 03:31:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:31:49 INFO - 'SHELL': '/bin/bash', 03:31:49 INFO - 'SHLVL': '1', 03:31:49 INFO - 'TERM': 'linux', 03:31:49 INFO - 'TMOUT': '86400', 03:31:49 INFO - 'USER': 'cltbld', 03:31:49 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300', 03:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:31:49 INFO - '_': '/tools/buildbot/bin/python'} 03:31:49 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/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:31:49 INFO - mozversion application_buildid: 20160726004006 03:31:49 INFO - mozversion application_changeset: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 03:31:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:31:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:31:49 INFO - mozversion application_name: Firefox 03:31:49 INFO - mozversion application_remotingname: firefox-dev 03:31:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:49 INFO - mozversion application_vendor: Mozilla 03:31:49 INFO - mozversion application_version: 49.0a2 03:31:49 INFO - mozversion platform_buildid: 20160726004006 03:31:49 INFO - mozversion platform_changeset: 517663b29d7ffc2ce91f90dc7beaac4fa35e52e5 03:31:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:31:49 INFO - mozversion platform_version: 49.0a2 03:31:49 INFO - using testdate: 1469529109 03:31:49 INFO - actual date: 1469529109 03:31:49 INFO - starting webserver on 'localhost:39803' 03:31:49 INFO - SUITE-START | Running 2 tests 03:31:49 INFO - TEST-START | dromaeo_css 03:31:49 INFO - operating with platform_type : linux_ 03:31:49 INFO - Initialising browser for dromaeo_css test... 03:31:49 INFO - TEST-INFO | started process 24345 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfc3U5P/profile http://localhost:39803/getInfo.html) 03:31:53 INFO - PROCESS | 24345 | __metrics Screen width/height:1600/1200 03:31:53 INFO - PROCESS | 24345 | colorDepth:24 03:31:53 INFO - PROCESS | 24345 | Browser inner width/height: 1024/697 03:31:53 INFO - PROCESS | 24345 | __metrics 03:31:54 INFO - PROCESS | 24345 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:31:54 ERROR - PROCESS | 24345 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:31:55 INFO - TEST-INFO | 24345: exit 0 03:31:55 INFO - Browser initialized. 03:31:55 INFO - Running cycle 1/1 for dromaeo_css test... 03:31:55 INFO - TEST-INFO | started process 24437 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfc3U5P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:32:07 INFO - PROCESS | 24437 | RSS: Main: 145465344 03:32:07 INFO - PROCESS | 24437 | 03:32:58 INFO - PROCESS | 24437 | 1469529178968 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:35:06 INFO - PROCESS | 24437 | Cycle 1(1): loaded http://localhost:39803/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39803/tests/dromaeo/cssquery-ext.html) 03:35:07 INFO - PROCESS | 24437 | RSS: Main: 254386176 03:35:07 INFO - PROCESS | 24437 | 03:38:10 INFO - PROCESS | 24437 | Cycle 1(1): loaded http://localhost:39803/tests/dromaeo/cssquery-ext.html (next: http://localhost:39803/tests/dromaeo/cssquery-jquery.html) 03:38:10 INFO - PROCESS | 24437 | RSS: Main: 230506496 03:38:10 INFO - PROCESS | 24437 | 03:41:12 INFO - PROCESS | 24437 | Cycle 1(1): loaded http://localhost:39803/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39803/tests/dromaeo/cssquery-mootools.html) 03:41:13 INFO - PROCESS | 24437 | RSS: Main: 232951808 03:41:13 INFO - PROCESS | 24437 | 03:44:15 INFO - PROCESS | 24437 | Cycle 1(1): loaded http://localhost:39803/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39803/tests/dromaeo/cssquery-prototype.html) 03:44:15 INFO - PROCESS | 24437 | RSS: Main: 224256000 03:44:15 INFO - PROCESS | 24437 | 03:47:18 INFO - PROCESS | 24437 | Cycle 1(1): loaded http://localhost:39803/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39803/tests/dromaeo/cssquery-yui.html) 03:47:19 INFO - PROCESS | 24437 | RSS: Main: 220065792 03:47:19 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | Cycle 1(1): loaded http://localhost:39803/tests/dromaeo/cssquery-yui.html (next: http://localhost:39803/tests/dromaeo/cssquery-dojo.html) 03:50:21 INFO - PROCESS | 24437 | RSS: Main: 259842048 03:50:21 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | __start_tp_report 03:50:21 INFO - PROCESS | 24437 | _x_x_mozilla_page_load 03:50:21 INFO - PROCESS | 24437 | _x_x_mozilla_page_load_details 03:50:21 INFO - PROCESS | 24437 | |i|pagename|runs| 03:50:21 INFO - PROCESS | 24437 | |0;dojo.html;3263.265765202829;3278.70491655751;3286.1127495576193;3311.933761324775;3324.717399021083;5394.083005888999;5476.014317422862;5493.835184944448;5495.093309603911;5524.447555244478;5478.945210547893;5556.333240011194;5562.499375056244;5562.527185189264;5586.190002449643;4953.306537084811;4985.302057711923;5001.399832020163;5021.046001259767;5061.16490779021;5012.649114561982;5086;5089.46560611136;5091.643584949055;5095.872603184913;4914.705117692932;4941.061198372316;4961.950380496203;4965.677230979974;5087.56755675768;5030.924536131961;5040.34475518184;5051.722155281458;5065.4174769901565;5091.974540127313;4851.563359297645;4884.731339776311;4931.235658472907;4959.7024178549045;4984.0281145176705;5299.284596579451;5320.787168513255;5323.920141197885;5328.360596728378;5392.91910621341;5868.97065514674;5882.882342353129;5889.587728858982;5898.882022359572;5926.170336152942;5736.2256095427;5739.885202295931;5793.623414478046;5859.736311865933;5963.970180149072;5411.269478620376;5465.098258787335;5470.070088085035;5471.398146203915;5472.26124473195;5780;5963.403659634088;5970.462658360769;5977.043673012297;5982.790602328898;5757.021306377926;5801.7099145042575;5814.941850581525;5874.32445268791;5908.675022873697;5915.674637894871;5922.2597175353085;5928.318243402064;5948.821535353946;5953.732082056318;5608.803691870847;5810.157527158538;5928.407159284037;5928.466438020599;5936.5250779937505;5867.14926335679;5906.143609176731;5908.645481271137;5911.379305172895;5933.1396947443245;4544.2047641662575;4545.227311357077;4548.863534093916;4549.27211646135;4578.084383123389;3991.1818077294083;3993.7204395692024;4029.2747305485295;4075.9606300393802;4115.814788334532;5589;5608.971955140199;5612.07400778875;5616.354119276336;5634.3802181759975;5916.467517923407;5921.792737254175;5932.881342373128;5933.495652869468;5952.851178720567;5524.972375138099;5577.8047768327915;5587.776488940478;5599.944000560024;5633.0987042073075;5120.590352771769;5143.177091665316;5151.098557752378;5151.2530683050745;5151.25306830515;6135.294441139326;6181.876362472725;6182.505399568024;6190.535709821781;6198.473129784019;5804.100364443518;5837.241158649348;5893.292804863444;5899.764009439575;5944.3164036136395;6131.264248290234;6135.11040899076;6138.232720909887;6140.355262697352;6147.170132032297;5570.108782594765;5804.506616937608;5809.477147056785;5810.854728631818;5812.18629391877;5839.5036421904615;5871.119332100219;5924.348321684525;5960.672163030901;5965.6718880461995;5799;5828.679422631796;5831.912521312099;5842.094475356326;5846.444587764156;5651.717414129359;5683.431656834284;5775.306963164448;5818.301803783573;5838.678872661874;5571.637843540157;5653.321601407857;5654.6324488908085;5661.150827375762;5684.147377893349;5744.770209191585;5754.9424505754405;5789.53683705311;5793.478586927196;5797.971010144923;5560.749766260609;5605.719714014364;5632.549396048388;5643.181738647955;5679.716014199356 03:50:21 INFO - PROCESS | 24437 | |1;ext.html;618.514466144077;651.3096118114797;652.3378770547894;652.8726898254839;666.7766298290072;33190;36054.278914421695;36061.27877442449;36082.63917360826;36087.097822554446;47592.52407475924;48667;48776.51223487764;52951.20573191405;52963.205551916755;9172.403793753401;9298.53507324631;9314.813703725922;9315.72052838416;9320.300977426687;8407.5375854328;9173.95413022934;9262.305327100461;9282.443053416782;10813.945930270338;10890.183236257273;11325.660230193107;11327.093832493421;11334.433278336091;11334.886651133465;11176.664700059011;11243.325400475998;11247.100231981422;11256.043236324882;11267.830982535186;8294.419390642657;10703.197260205437;10945.507452164671;11144.777104457946;11150.776984460266;41242;45310.32034519485;45412;45722;45741.085178296584;3555.3600351936643;3581.9104522387147;3609.747317687763;3809.5619003814363;4453.77731113443;35306;35373.46939795905;35474.46788298152;35805.28389432197;35835;4796.400449943757;4871.805127794884;4945.159322915077;4951.579115775164;4968.3292755477905;398343;399920;401196;401292;401354;38442.80778596089;70953.64523177351;71311.2868871315;71577.2842271581;71851.28148718551;39249;41321.58678413238;41334;41399;41437;14833.703325933637;14860.405583776745;14967.925160374347;14972.251387430802;14982.250887455803;12281.877181228252;12333.814992775115;12340.321282329378;12352.505899764088;12373.443195056065;8765.561721914006;8919.910800892038;9006.099390060941;9154.542272886329;9189.264858811279;3510.8215382310887;3516.9296614067575;3535.7878527288444;3566.1084728817796;3609.801460919622;1083.669480808361;1086.0442810326863;1117.407773879845;1174.5360582570008;1190.2798806721983;35052.223216950064;35386;35421.64578354235;35637;35782.28435431277;1184.8874356936078;1207.5532053140219;1256.4911210959576;1261.9747605047849;1264.0393301091053;17774.111294435228;18200;18255.90872045631;18282.177302021442;18351.449456516326;649306.2742803979;658997;661258;661373;662838;63433;63671.36328636654;63704.68147659325;63896.36103638904;63986;1321.73565286943;1418.6098822823806;1421.7394342994687;1425.4298280687808;1454.8472410396755;34137.146571335914;34665.65334346624;34947;35093;35154.82422587871;54191;54347.18479222736;54676;54771;54832;463049;463767;464086;465380;465479;27476.03833865805;30224.84887575548;30965.535516966815;31277.374452511278;32431.513527297582;50891.49108508867;52020.73989630028;52709.73645131903;53049.734751325996;53559;44756.55243447524;44930.55069449264;45149.77425112853;45486.92226079798;46224.537754622026;44198.55801442073;44518;44544.33183502315;45005.32492012688;45545;44584.55415445804;45152.09695806131;45690.77154614338;45720.77139614281;45959;37277.62722372742;39656.206875862896;39678.404823927085;39738.205235894544;40395.79802100971;29858.850705746332;29863.55204671975;30116.397672046875;30500.389992199587;30548.236294092825 03:50:21 INFO - PROCESS | 24437 | |2;jquery.html;422.64497821829656;428.17148817039015;429.83666206841394;435.9041010977585;440.929451287794;18792.718109228357;19624.116914738835;19753.604927901433;20049.59900801983;20566.794332056674;16881.24034418451;16954.745678814827;17130.657386852254;17175.141242937865;17296.135193240352;5487.64330318529;5705.515031222351;5710.714464276791;5720.399358067405;5727.971360143214;4153.190127925056;5636.915446268309;5693.146028095798;5993.730282137292;6307.810765677037;6385.776497822577;6392.721455708854;6393.392627700361;6401.679916004205;6419.871602567946;6287.968560157193;6305.747770089191;6424.775132870327;6429.4534964528075;6441.645709485976;3820.732548721591;5337.839864804062;5688.203651488795;5695.829125126253;5708.20085188074;23037.76962230373;23095.0761969521;23196.65205021926;23326.53346933052;23362.883185584134;2323.8373313868037;2502;2521.8612976286295;2538.708048574419;3109.378124375134;10428.426436545988;10460.52927618259;10468.319559228627;10987.340759554372;11029.007389334998;1974.062320397814;2024.6760518316999;2026.311054241565;2067.4211220858183;2147.3665268745603;56024.159637605466;56175;74308;83595.58202209011;92719.53640231823;11311.60409385684;11509.76980460386;11562.479688414041;11752.412379380996;11838.230515016669;16359.918200408922;16444.424445144363;16614.83385166133;16626.83373166253;16626.916865415595;7759.72840950564;7969.163237860057;8062.072861620872;8161.143079976636;8225.83548329042;7611.847763044819;7618.4667073305445;7657.6554055066545;7749.263819937097;7754.340881025063;4945.307656928033;4967.006598680278;5017.498250174954;5051.4695956924725;5140.151874940668;1832.2029916986153;1845.4924895653803;1858.1916866163263;1961.6665166921657;2034.52188735647;966.21253678252;1021.4126877045728;1039.1998161415668;1052.9473526323652;1058.714147180257;18629.534261643566;18845.905770471334;18906.218756248807;18948.715769263472;19025.334113306246;1009.9040591143829;1015.9441230732233;1037.7872536130076;1047.800917825626;1103.2773533335678;10484.423356715453;10825.67522974312;10841.728956775985;10878.673639790819;10956.34261944286;477873;479444;480420;487408;488036;60841;77357.83963240556;93762.53118734363;94217.528912355;94640.52679736557;1023.9846402303972;1034.4000479721744;1053.073295499955;1057.0750593230923;1059.4467333725684;16548.172591370625;16970.151492425084;17067.658646827244;17179.226934788214;17372;39381;40818.9795255121;41483.37774933439;42773.57226427696;42889;59819;60032;60202;60793;61499;8501.787455313666;8937.195652391316;8992.595333210153;9053.818923621358;9219.631214751334;9834.901650983398;13058.542950996669;13254.138480998705;13298.135621184592;13433.596992090253;10713.410762407831;10914.181436392144;10991.175661825235;11029;11226.382548959886;11174.385408802595;11349.943250283695;11438.82841757391;11729.237599556001;11784.116191285517;11152;11333.093352531943;11334.546618135511;11366;11444.084473241954;8699;9043.00526942049;9082;9085.273178145864;9139.086091390807;10521.579136834442;11062.502187401426;11099.556017759522;11127.499262533367;11160.10719142483 03:50:21 INFO - PROCESS | 24437 | |3;mootools.html;1468.8898332625056;1524.1083965879957;1569.8822588305877;1780.8486278666312;1855.6938105212641;9342.486163261017;9766.609335626576;9791.216702663787;9798.314118011724;9804.362716423437;9474.478903660312;9626.326157169002;9667.419954802717;9672.371295865763;9682.951585242065;2539.555577773887;2565.486902619474;2567.627693984371;2567.7303883092286;2567.871606419681;2515.2579988903317;2564.192279431982;2566.1275166443406;2567.114345550782;2569.3191304304373;2476.839005464648;2486.502699460106;2514.735952724965;2516.1319344826006;2519.282004628681;2386.570417324881;2417.830751847371;2440.2313271319567;2449.155041510676;2451.1053465485147;2328.0571368595747;2333.801626861719;2335.976640233593;2336;2417.359399759065;9346.345755797098;9365.250779937589;9469.195118414944;9484.241260699197;9699.224062075087;5371.435999220064;5486.561075113981;5516.613837031409;5517.172424136372;5522;3602.819859007039;3625.474306225609;3626.5829429615405;3745.232227393378;3745.3071181831447;1251.3743128435783;1263.5577547858268;1269.1306455078302;1278.9360531973364;1293.2111412038648;13730.65673358164;13807.585772426843;13854;13875;13885.722285554233;4790.784414701347;4868.318435419044;4882.194437917704;4884.902301954012;4947.2579113133315;10767.6231331903;10813.351198928089;10822.296550724177;10833.29583577065;10839.295445795999;5365.463453654604;5368.32895888014;5368.91946620801;5369.785208591613;5372.355317361941;4243.936340954888;4685.508021657745;4878.341423907798;4903.166461701519;4920.21276595743;4315.741055536678;4405;4470.284754439274;4496.3705081288645;4516.16450956574;1342.2215115233337;1395.7278330725615;1398.6153807702963;1406.1633328169723;1410.2102822419479;855.7347222361088;950.8811398575178;1046.26238501856;1048.4390850894888;1049.5801679328329;6161.44546990773;6240.188775459162;6370.171877655967;6396;6627.701753421107;509.1065180608009;510.3110800419404;516.2591680937866;516.5506009771523;675.7161991963387;4286.249906266391;4366.541513141075;4381.759003254851;4396.230659634552;4409.867703968886;11531.077513798877;11853.88146118545;11858.584949526725;11862.703432414259;11992.100592455601;11542;11661.300321980709;11683.941580292214;11961.760764784656;11969.820452693217;8643.481391116553;8738.86891696625;8749.693760718472;8769.123087691307;8802.07578204292;6527.086207930798;6559.423865435429;6591.868162636816;6609.173853268341;6768.578598494371;10765.623203187852;11047.558097676325;11133.832992504957;11171.162162837658;11357.545698172311;11240.887591123985;11376.089912807121;11424.543018279175;11564.537418503165;11576.36330001858;7907;8122.5126492410645;8129.634166462405;8389.874151887849;8530.317574594143;8854.380193386658;9114.40756350835;9275.118863707938;9350.345475816628;9612.75968100775;8077.111517733162;8105.067917189599;8185.467944583583;8224.3831712623;8230.341572674051;8125;8148.592570371577;8211.302039326723;8244.958775206085;8315.792105197419;7612.048493938258;7625.885611715939;7688.961555192188;7749.883751743616;7752.689892404241;5428.755705993319;5458.208559758733;5552.750126244409;5610.7755689773585;5727.198192253171;5558.305211848519;5633.521150702236;5687.033204355353;5841.474267315792;5936.821895343147 03:50:21 INFO - PROCESS | 24437 | |4;prototype.html;350.18057744877;354.3179379694089;362.0387870204607;363.35323124837765;365.2074997255954;5344.518993290603;5423;5459.344878614562;5489.368722596902;5506.889862202754;5308.495692909168;5330.706811125387;5354.41101478837;5389.78440862365;5395.81114660987;3826.789526576037;4006.298897692907;4230.407742915994;4232.957670423287;4249.19265339586;4225.556316586745;4232.3016702244095;4237.364395340693;4249.19265339586;4261.573842615745;4050.8784736457806;4203.726757760751;4219.367094935754;4224.746515209082;4229.830806767725;4151.460310159675;4235.555266697014;4250.511191213003;4260.105377870651;4272.871813845559;3743.6630703236565;3776.9613356326954;3781.357169281201;3799.734018618698;3802.8478860845535;5196.974015129938;5245.659032162936;5261.316028916254;5264.368275806889;5273.578113750891;4895.510448955111;4907.214845624719;4912.606991440676;4932.383452068491;4959.181735013718;5600;5635.21107045014;5640.884668249407;5645.237892884449;5646.463585959327;4058.533268674111;4166.166766646683;4173.165366926627;4186.497620285555;4204.684648651333;5579.776808927638;5705.172749951275;5799.797007104731;5838;5883.294004719418;4211.642010429086;4291.184674911757;4313.698041137153;4313.956860431355;4325.723911446068;4331.956680433155;4336.696431249782;4338.804753786024;4349.717268377545;4372.628326592212;4765.309030190603;4815.277708343777;4883.95116048835;4897.608191344684;4899.853004409873;5637.43625637433;5639.830805075854;5653.9151912721345;5665.555283402706;5701.486866182064;1477.9358861619617;1498.2808252038865;1505.1947208243537;1507.4799194277957;1507.6457032597332;924.7965447601517;956.2254573795109;970.6214576315243;975.7365511311906;977.130353984954;4901.117798796251;4907.214845624683;4928.753562321869;4951.3810773653295;4957.950420495749;5143.691378517226;5151.407588127338;5153.561947234527;5179.249008893689;5218.225803226921;4677.368555245066;4981.402231732215;4985.55130038291;5007.069450481355;5029.622778291643;2739;2769.6042922871975;2781.3602871339704;2783.930401739932;2814.591884176783;5320.494553017457;5339.252504649352;5351.866203344947;5383.865403364947;5439.945600543944;5222.529972302511;5254.7635356409055;5505.697186654773;5931.703414829241;5987.79042733511;6035.698215089228;6053.667048312385;6080.391960803884;6087.1173679816175;6090.543209259324;5626.521745651666;5834.18321435008;5838.737256823369;5842.824715258549;5852.619579727304;5707.914381284367;5751.252337196305;5751.654900705971;5763.884722305446;5780.248567686174;5787.392323806108;5862.443067908542;6019.879602408015;6051.485623721856;6058.818235452944;4103.548609652876;4110.588941105865;4124.52567954677;4136.96575856036;4142.1922725068325;5700;5770.163326317745;5794.536437084938;5873.823785286449;5875.941240587539;5632.098864181711;5647.774089036557;5653.519450846724;5657.264555607745;5681.289838770153;5609.214709940693;5619.269494965628;5621.662700237999;5626.959012582685;5637;5354.3574771027725;5529.225908372911;5535.612507124623;5552.917181149637;5587.497125258747;5619.522340601095;5641.448601634584;5644.520215781705;5654.6324488908085;5670.773169073191;5368.731563421735;5536.837264174568;5546.805861794979;5551.833444996657;5569.248151499496 03:50:21 INFO - PROCESS | 24437 | |5;yui.html;847.6439895243993;853.71827296992;855.6662901468425;861.4314552395418;922.3313098003946;15131.092134471939;16197.10915899628;16243.593910152253;16245.756313655289;16295.674086518262;15805.525834224964;16291.91854040731;16311.510654680378;16862.494125176232;16917.407890723825;702.5257950883159;837.3426859914968;852.4672079950046;854.811941372897;862.8317478091775;860.311750599521;860.9569521523899;863.4732812984074;864.0322838420946;864.11859902899;895.583553647557;897.5512243878061;897.9506127163004;898.0166717444421;898.4160295807744;745.437194917839;890.0832142892831;892.9821403571925;913.584319134792;915.7847905742145;653.1150291355241;653.5980372071142;654.1006116589689;654.2639530528156;656.6782276684438;16358.754618680729;16513.256903439375;16529.917350413292;16557.91721041387;16590.66818663632;14729.55811325662;15299.235038248044;15453.150076745775;16542.586435339093;16935.153242337834;14150.976264066598;14505.854941450556;14599.270036498132;14671.77992330116;15139;4794.280857871312;4899.216125419915;4928.901421971576;4934.235193545006;5059.595232381437;66995.3300466994;72780.63609681971;75512.24487755107;79724;80447;12605.05462090347;13009.544665936646;13200.009999250095;13204.735905282032;13454.32728363578;24491.632625510632;24495.38761530976;24534.386640334134;24549.877250613634;24567.87716061444;9321.347505674674;9441.386309889835;9501.772793233975;9746.463944482979;9820.852687209697;5100.285959965608;5181.4300426953005;5187.147963470205;5220.190870415092;5358.624896257303;3786.3941769316775;3887.902802429962;3892.649661530476;3895.49358583388;3904.1020565270146;585.1515302810941;606.6523594514342;706.1878839334806;714.0574441736961;714.9561640005546;685.3352248319122;685.9245482996866;724.2938135318022;744.4677055905054;753.6834529497625;11221.495032723547;11298;11410.714732131763;11568.652940411801;11722.531098756124;747.484235877243;751.6692655231681;762.3101093510382;782.1357400072837;782.8121250899743;5072.82245121419;5135.332406787168;5152.613553983466;5199.402068762065;5246.921296180561;129502.35248823884;130183;132570;132697.33651331873;135095;86955;95704.52147739356;107450.46274768576;108542;109293.45353273183;86726;88522;89240;90423;90818;10660.520276587727;10866;10915.290506117077;10966.122710183006;10982.835257470984;38372.6162738369;41665.37501937413;47153.292700608836;51253.231201532755;55540;69492;74702.62648686722;81643.5917820407;81670;82333.17666823494;13191.208527488381;13881.791773123614;14004.43982240699;14342.713145737236;14488.420463181355;15977.520674379788;16117.435889743803;16153.75769363437;16284.267207975432;16950.067746274075;11695.934637811897;13987.720245594828;14780.852191477947;14924.55226343168;16008.599785005003;13848.584542463743;14263.215523146326;14847.034942728687;14893.180875051976;15470.767938480707;14835;14909.627259318604;15314;15332.846671533589;15387.692246155239;12251.38743062861;12379;12871.678208044874;13098.410571524495;13107.737845243233;11823.467943942715;12327.075469339656;12331.383430828602;12587.685307867376;12666.936665316614 03:50:21 INFO - PROCESS | 24437 | __end_tp_report 03:50:21 INFO - PROCESS | 24437 | __start_cc_report 03:50:21 INFO - PROCESS | 24437 | _x_x_mozilla_cycle_collect,879 03:50:21 INFO - PROCESS | 24437 | __end_cc_report 03:50:21 INFO - PROCESS | 24437 | __startTimestamp1469530221859__endTimestamp 03:50:21 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | ------- Summary: start ------- 03:50:21 INFO - PROCESS | 24437 | Number of tests: 6 03:50:21 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | [#0] dojo.html Cycles:165 Average:5474.61 Median:5651.72 stddev:607.51 (10.7%) stddev-sans-first:584.08 03:50:21 INFO - PROCESS | 24437 | Values: 3263.3 3278.7 3286.1 3311.9 3324.7 5394.1 5476.0 5493.8 5495.1 5524.4 5478.9 5556.3 5562.5 5562.5 5586.2 4953.3 4985.3 5001.4 5021.0 5061.2 5012.6 5086.0 5089.5 5091.6 5095.9 4914.7 4941.1 4962.0 4965.7 5087.6 5030.9 5040.3 5051.7 5065.4 5092.0 4851.6 4884.7 4931.2 4959.7 4984.0 5299.3 5320.8 5323.9 5328.4 5392.9 5869.0 5882.9 5889.6 5898.9 5926.2 5736.2 5739.9 5793.6 5859.7 5964.0 5411.3 5465.1 5470.1 5471.4 5472.3 5780.0 5963.4 5970.5 5977.0 5982.8 5757.0 5801.7 5814.9 5874.3 5908.7 5915.7 5922.3 5928.3 5948.8 5953.7 5608.8 5810.2 5928.4 5928.5 5936.5 5867.1 5906.1 5908.6 5911.4 5933.1 4544.2 4545.2 4548.9 4549.3 4578.1 3991.2 3993.7 4029.3 4076.0 4115.8 5589.0 5609.0 5612.1 5616.4 5634.4 5916.5 5921.8 5932.9 5933.5 5952.9 5525.0 5577.8 5587.8 5599.9 5633.1 5120.6 5143.2 5151.1 5151.3 5151.3 6135.3 6181.9 6182.5 6190.5 6198.5 5804.1 5837.2 5893.3 5899.8 5944.3 6131.3 6135.1 6138.2 6140.4 6147.2 5570.1 5804.5 5809.5 5810.9 5812.2 5839.5 5871.1 5924.3 5960.7 5965.7 5799.0 5828.7 5831.9 5842.1 5846.4 5651.7 5683.4 5775.3 5818.3 5838.7 5571.6 5653.3 5654.6 5661.2 5684.1 5744.8 5754.9 5789.5 5793.5 5798.0 5560.7 5605.7 5632.5 5643.2 5679.7 03:50:21 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | [#1] ext.html Cycles:180 Average:66512.50 Median:399131.50 stddev:138521.65 (34.7%) stddev-sans-first:138821.89 03:50:21 INFO - PROCESS | 24437 | Values: 618.5 651.3 652.3 652.9 666.8 33190.0 36054.3 36061.3 36082.6 36087.1 47592.5 48667.0 48776.5 52951.2 52963.2 9172.4 9298.5 9314.8 9315.7 9320.3 8407.5 9174.0 9262.3 9282.4 10813.9 10890.2 11325.7 11327.1 11334.4 11334.9 11176.7 11243.3 11247.1 11256.0 11267.8 8294.4 10703.2 10945.5 11144.8 11150.8 41242.0 45310.3 45412.0 45722.0 45741.1 3555.4 3581.9 3609.7 3809.6 4453.8 35306.0 35373.5 35474.5 35805.3 35835.0 4796.4 4871.8 4945.2 4951.6 4968.3 398343.0 399920.0 401196.0 401292.0 401354.0 38442.8 70953.6 71311.3 71577.3 71851.3 39249.0 41321.6 41334.0 41399.0 41437.0 14833.7 14860.4 14967.9 14972.3 14982.3 12281.9 12333.8 12340.3 12352.5 12373.4 8765.6 8919.9 9006.1 9154.5 9189.3 3510.8 3516.9 3535.8 3566.1 3609.8 1083.7 1086.0 1117.4 1174.5 1190.3 35052.2 35386.0 35421.6 35637.0 35782.3 1184.9 1207.6 1256.5 1262.0 1264.0 17774.1 18200.0 18255.9 18282.2 18351.4 649306.3 658997.0 661258.0 661373.0 662838.0 63433.0 63671.4 63704.7 63896.4 63986.0 1321.7 1418.6 1421.7 1425.4 1454.8 34137.1 34665.7 34947.0 35093.0 35154.8 54191.0 54347.2 54676.0 54771.0 54832.0 463049.0 463767.0 464086.0 465380.0 465479.0 27476.0 30224.8 30965.5 31277.4 32431.5 50891.5 52020.7 52709.7 53049.7 53559.0 44756.6 44930.6 45149.8 45486.9 46224.5 44198.6 44518.0 44544.3 45005.3 45545.0 44584.6 45152.1 45690.8 45720.8 45959.0 37277.6 39656.2 39678.4 39738.2 40395.8 29858.9 29863.6 30116.4 30500.4 30548.2 03:50:21 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | [#2] jquery.html Cycles:180 Average:28396.17 Median:12760.24 stddev:79403.70 (622.3%) stddev-sans-first:79598.67 03:50:21 INFO - PROCESS | 24437 | Values: 422.6 428.2 429.8 435.9 440.9 18792.7 19624.1 19753.6 20049.6 20566.8 16881.2 16954.7 17130.7 17175.1 17296.1 5487.6 5705.5 5710.7 5720.4 5728.0 4153.2 5636.9 5693.1 5993.7 6307.8 6385.8 6392.7 6393.4 6401.7 6419.9 6288.0 6305.7 6424.8 6429.5 6441.6 3820.7 5337.8 5688.2 5695.8 5708.2 23037.8 23095.1 23196.7 23326.5 23362.9 2323.8 2502.0 2521.9 2538.7 3109.4 10428.4 10460.5 10468.3 10987.3 11029.0 1974.1 2024.7 2026.3 2067.4 2147.4 56024.2 56175.0 74308.0 83595.6 92719.5 11311.6 11509.8 11562.5 11752.4 11838.2 16359.9 16444.4 16614.8 16626.8 16626.9 7759.7 7969.2 8062.1 8161.1 8225.8 7611.8 7618.5 7657.7 7749.3 7754.3 4945.3 4967.0 5017.5 5051.5 5140.2 1832.2 1845.5 1858.2 1961.7 2034.5 966.2 1021.4 1039.2 1052.9 1058.7 18629.5 18845.9 18906.2 18948.7 19025.3 1009.9 1015.9 1037.8 1047.8 1103.3 10484.4 10825.7 10841.7 10878.7 10956.3 477873.0 479444.0 480420.0 487408.0 488036.0 60841.0 77357.8 93762.5 94217.5 94640.5 1024.0 1034.4 1053.1 1057.1 1059.4 16548.2 16970.2 17067.7 17179.2 17372.0 39381.0 40819.0 41483.4 42773.6 42889.0 59819.0 60032.0 60202.0 60793.0 61499.0 8501.8 8937.2 8992.6 9053.8 9219.6 9834.9 13058.5 13254.1 13298.1 13433.6 10713.4 10914.2 10991.2 11029.0 11226.4 11174.4 11349.9 11438.8 11729.2 11784.1 11152.0 11333.1 11334.5 11366.0 11444.1 8699.0 9043.0 9082.0 9085.3 9139.1 10521.6 11062.5 11099.6 11127.5 11160.1 03:50:21 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | [#3] mootools.html Cycles:180 Average:6256.71 Median:4829.55 stddev:3637.73 (75.3%) stddev-sans-first:3630.14 03:50:21 INFO - PROCESS | 24437 | Values: 1468.9 1524.1 1569.9 1780.8 1855.7 9342.5 9766.6 9791.2 9798.3 9804.4 9474.5 9626.3 9667.4 9672.4 9683.0 2539.6 2565.5 2567.6 2567.7 2567.9 2515.3 2564.2 2566.1 2567.1 2569.3 2476.8 2486.5 2514.7 2516.1 2519.3 2386.6 2417.8 2440.2 2449.2 2451.1 2328.1 2333.8 2336.0 2336.0 2417.4 9346.3 9365.3 9469.2 9484.2 9699.2 5371.4 5486.6 5516.6 5517.2 5522.0 3602.8 3625.5 3626.6 3745.2 3745.3 1251.4 1263.6 1269.1 1278.9 1293.2 13730.7 13807.6 13854.0 13875.0 13885.7 4790.8 4868.3 4882.2 4884.9 4947.3 10767.6 10813.4 10822.3 10833.3 10839.3 5365.5 5368.3 5368.9 5369.8 5372.4 4243.9 4685.5 4878.3 4903.2 4920.2 4315.7 4405.0 4470.3 4496.4 4516.2 1342.2 1395.7 1398.6 1406.2 1410.2 855.7 950.9 1046.3 1048.4 1049.6 6161.4 6240.2 6370.2 6396.0 6627.7 509.1 510.3 516.3 516.6 675.7 4286.2 4366.5 4381.8 4396.2 4409.9 11531.1 11853.9 11858.6 11862.7 11992.1 11542.0 11661.3 11683.9 11961.8 11969.8 8643.5 8738.9 8749.7 8769.1 8802.1 6527.1 6559.4 6591.9 6609.2 6768.6 10765.6 11047.6 11133.8 11171.2 11357.5 11240.9 11376.1 11424.5 11564.5 11576.4 7907.0 8122.5 8129.6 8389.9 8530.3 8854.4 9114.4 9275.1 9350.3 9612.8 8077.1 8105.1 8185.5 8224.4 8230.3 8125.0 8148.6 8211.3 8245.0 8315.8 7612.0 7625.9 7689.0 7749.9 7752.7 5428.8 5458.2 5552.8 5610.8 5727.2 5558.3 5633.5 5687.0 5841.5 5936.8 03:50:21 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | [#4] prototype.html Cycles:180 Average:4701.51 Median:5268.97 stddev:1363.76 (25.9%) stddev-sans-first:1327.90 03:50:21 INFO - PROCESS | 24437 | Values: 350.2 354.3 362.0 363.4 365.2 5344.5 5423.0 5459.3 5489.4 5506.9 5308.5 5330.7 5354.4 5389.8 5395.8 3826.8 4006.3 4230.4 4233.0 4249.2 4225.6 4232.3 4237.4 4249.2 4261.6 4050.9 4203.7 4219.4 4224.7 4229.8 4151.5 4235.6 4250.5 4260.1 4272.9 3743.7 3777.0 3781.4 3799.7 3802.8 5197.0 5245.7 5261.3 5264.4 5273.6 4895.5 4907.2 4912.6 4932.4 4959.2 5600.0 5635.2 5640.9 5645.2 5646.5 4058.5 4166.2 4173.2 4186.5 4204.7 5579.8 5705.2 5799.8 5838.0 5883.3 4211.6 4291.2 4313.7 4314.0 4325.7 4332.0 4336.7 4338.8 4349.7 4372.6 4765.3 4815.3 4884.0 4897.6 4899.9 5637.4 5639.8 5653.9 5665.6 5701.5 1477.9 1498.3 1505.2 1507.5 1507.6 924.8 956.2 970.6 975.7 977.1 4901.1 4907.2 4928.8 4951.4 4958.0 5143.7 5151.4 5153.6 5179.2 5218.2 4677.4 4981.4 4985.6 5007.1 5029.6 2739.0 2769.6 2781.4 2783.9 2814.6 5320.5 5339.3 5351.9 5383.9 5439.9 5222.5 5254.8 5505.7 5931.7 5987.8 6035.7 6053.7 6080.4 6087.1 6090.5 5626.5 5834.2 5838.7 5842.8 5852.6 5707.9 5751.3 5751.7 5763.9 5780.2 5787.4 5862.4 6019.9 6051.5 6058.8 4103.5 4110.6 4124.5 4137.0 4142.2 5700.0 5770.2 5794.5 5873.8 5875.9 5632.1 5647.8 5653.5 5657.3 5681.3 5609.2 5619.3 5621.7 5627.0 5637.0 5354.4 5529.2 5535.6 5552.9 5587.5 5619.5 5641.4 5644.5 5654.6 5670.8 5368.7 5536.8 5546.8 5551.8 5569.2 03:50:21 INFO - PROCESS | 24437 | 03:50:21 INFO - PROCESS | 24437 | [#5] yui.html Cycles:180 Average:22145.80 Median:3890.28 stddev:31721.06 (815.4%) stddev-sans-first:31769.73 03:50:21 INFO - PROCESS | 24437 | Values: 847.6 853.7 855.7 861.4 922.3 15131.1 16197.1 16243.6 16245.8 16295.7 15805.5 16291.9 16311.5 16862.5 16917.4 702.5 837.3 852.5 854.8 862.8 860.3 861.0 863.5 864.0 864.1 895.6 897.6 898.0 898.0 898.4 745.4 890.1 893.0 913.6 915.8 653.1 653.6 654.1 654.3 656.7 16358.8 16513.3 16529.9 16557.9 16590.7 14729.6 15299.2 15453.2 16542.6 16935.2 14151.0 14505.9 14599.3 14671.8 15139.0 4794.3 4899.2 4928.9 4934.2 5059.6 66995.3 72780.6 75512.2 79724.0 80447.0 12605.1 13009.5 13200.0 13204.7 13454.3 24491.6 24495.4 24534.4 24549.9 24567.9 9321.3 9441.4 9501.8 9746.5 9820.9 5100.3 5181.4 5187.1 5220.2 5358.6 3786.4 3887.9 3892.6 3895.5 3904.1 585.2 606.7 706.2 714.1 715.0 685.3 685.9 724.3 744.5 753.7 11221.5 11298.0 11410.7 11568.7 11722.5 747.5 751.7 762.3 782.1 782.8 5072.8 5135.3 5152.6 5199.4 5246.9 129502.4 130183.0 132570.0 132697.3 135095.0 86955.0 95704.5 107450.5 108542.0 109293.5 86726.0 88522.0 89240.0 90423.0 90818.0 10660.5 10866.0 10915.3 10966.1 10982.8 38372.6 41665.4 47153.3 51253.2 55540.0 69492.0 74702.6 81643.6 81670.0 82333.2 13191.2 13881.8 14004.4 14342.7 14488.4 15977.5 16117.4 16153.8 16284.3 16950.1 11695.9 13987.7 14780.9 14924.6 16008.6 13848.6 14263.2 14847.0 14893.2 15470.8 14835.0 14909.6 15314.0 15332.8 15387.7 12251.4 12379.0 12871.7 13098.4 13107.7 11823.5 12327.1 12331.4 12587.7 12666.9 03:50:21 INFO - PROCESS | 24437 | -------- Summary: end -------- 03:50:21 INFO - PROCESS | 24437 | 03:50:22 INFO - TEST-INFO | 24437: exit 0 03:50:22 INFO - TEST-OK | dromaeo_css | took 1113097ms 03:50:22 INFO - TEST-START | kraken 03:50:22 INFO - operating with platform_type : linux_ 03:50:22 INFO - Initialising browser for kraken test... 03:50:22 INFO - TEST-INFO | started process 24627 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEyXZ8n/profile http://localhost:39803/getInfo.html) 03:50:26 INFO - PROCESS | 24627 | __metrics Screen width/height:1600/1200 03:50:26 INFO - PROCESS | 24627 | colorDepth:24 03:50:26 INFO - PROCESS | 24627 | Browser inner width/height: 1024/697 03:50:26 INFO - PROCESS | 24627 | __metrics 03:50:27 INFO - PROCESS | 24627 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:50:27 ERROR - PROCESS | 24627 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:50:28 INFO - TEST-INFO | 24627: exit 0 03:50:28 INFO - Browser initialized. 03:50:28 INFO - Running cycle 1/1 for kraken test... 03:50:28 INFO - TEST-INFO | started process 24720 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEyXZ8n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:50:40 INFO - PROCESS | 24720 | RSS: Main: 145428480 03:50:40 INFO - PROCESS | 24720 | 03:50:43 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39803/tests/kraken/driver.html?testName=audio-beat-detection) 03:50:44 INFO - PROCESS | 24720 | RSS: Main: 195125248 03:50:44 INFO - PROCESS | 24720 | 03:50:46 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39803/tests/kraken/driver.html?testName=audio-dft) 03:50:47 INFO - PROCESS | 24720 | RSS: Main: 194674688 03:50:47 INFO - PROCESS | 24720 | 03:50:50 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39803/tests/kraken/driver.html?testName=audio-fft) 03:50:50 INFO - PROCESS | 24720 | RSS: Main: 280190976 03:50:50 INFO - PROCESS | 24720 | 03:50:52 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39803/tests/kraken/driver.html?testName=audio-oscillator) 03:50:53 INFO - PROCESS | 24720 | RSS: Main: 188952576 03:50:53 INFO - PROCESS | 24720 | 03:50:55 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39803/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:50:56 INFO - PROCESS | 24720 | RSS: Main: 189267968 03:50:56 INFO - PROCESS | 24720 | 03:50:59 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39803/tests/kraken/driver.html?testName=imaging-darkroom) 03:50:59 INFO - PROCESS | 24720 | RSS: Main: 224587776 03:50:59 INFO - PROCESS | 24720 | 03:51:03 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39803/tests/kraken/driver.html?testName=imaging-desaturate) 03:51:03 INFO - PROCESS | 24720 | RSS: Main: 225239040 03:51:03 INFO - PROCESS | 24720 | 03:51:06 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39803/tests/kraken/driver.html?testName=json-parse-financial) 03:51:06 INFO - PROCESS | 24720 | RSS: Main: 224579584 03:51:06 INFO - PROCESS | 24720 | 03:51:08 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39803/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:51:09 INFO - PROCESS | 24720 | RSS: Main: 201420800 03:51:09 INFO - PROCESS | 24720 | 03:51:10 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39803/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:51:11 INFO - PROCESS | 24720 | RSS: Main: 203333632 03:51:11 INFO - PROCESS | 24720 | 03:51:13 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39803/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:51:14 INFO - PROCESS | 24720 | RSS: Main: 209899520 03:51:14 INFO - PROCESS | 24720 | 03:51:16 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39803/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:51:17 INFO - PROCESS | 24720 | RSS: Main: 215490560 03:51:17 INFO - PROCESS | 24720 | 03:51:19 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39803/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:51:20 INFO - PROCESS | 24720 | RSS: Main: 214560768 03:51:20 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | Cycle 1(1): loaded http://localhost:39803/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39803/tests/kraken/driver.html?testName=ai-astar) 03:51:22 INFO - PROCESS | 24720 | RSS: Main: 257531904 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | __start_tp_report 03:51:22 INFO - PROCESS | 24720 | _x_x_mozilla_page_load 03:51:22 INFO - PROCESS | 24720 | _x_x_mozilla_page_load_details 03:51:22 INFO - PROCESS | 24720 | |i|pagename|runs| 03:51:22 INFO - PROCESS | 24720 | |0;ai-astar;111;102;109;109;111;109;110;109;108;109 03:51:22 INFO - PROCESS | 24720 | |1;audio-beat-detection;152;124;124;177;124;126;147;124;153;124 03:51:22 INFO - PROCESS | 24720 | |2;audio-dft;161;161;161;160;160;161;160;162;161;162 03:51:22 INFO - PROCESS | 24720 | |3;audio-fft;74;75;75;132;75;76;75;75;100;74 03:51:22 INFO - PROCESS | 24720 | |4;audio-oscillator;84;84;112;86;84;84;109;84;85;124 03:51:22 INFO - PROCESS | 24720 | |5;imaging-gaussian-blur;117;118;116;117;117;116;117;117;117;116 03:51:22 INFO - PROCESS | 24720 | |6;imaging-darkroom;146;146;145;145;145;145;146;145;145;146 03:51:22 INFO - PROCESS | 24720 | |7;imaging-desaturate;94;95;94;95;95;95;95;95;95;95 03:51:22 INFO - PROCESS | 24720 | |8;json-parse-financial;49;50;51;51;50;52;54;51;51;54 03:51:22 INFO - PROCESS | 24720 | |9;json-stringify-tinderbox;59;60;60;60;62;59;59;62;60;60 03:51:22 INFO - PROCESS | 24720 | |10;stanford-crypto-aes;76;75;75;76;76;78;81;78;125;76 03:51:22 INFO - PROCESS | 24720 | |11;stanford-crypto-ccm;123;117;118;127;134;115;127;118;123;128 03:51:22 INFO - PROCESS | 24720 | |12;stanford-crypto-pbkdf2;156;156;157;157;155;154;152;152;153;156 03:51:22 INFO - PROCESS | 24720 | |13;stanford-crypto-sha256-iterative;67;63;62;61;62;61;62;60;61;60 03:51:22 INFO - PROCESS | 24720 | __end_tp_report 03:51:22 INFO - PROCESS | 24720 | __start_cc_report 03:51:22 INFO - PROCESS | 24720 | _x_x_mozilla_cycle_collect,1588 03:51:22 INFO - PROCESS | 24720 | __end_cc_report 03:51:22 INFO - PROCESS | 24720 | __startTimestamp1469530282333__endTimestamp 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | ------- Summary: start ------- 03:51:22 INFO - PROCESS | 24720 | Number of tests: 14 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#0] ai-astar Cycles:10 Average:108.70 Median:109.00 stddev:2.54 (2.3%) stddev-sans-first:2.55 03:51:22 INFO - PROCESS | 24720 | Values: 111.0 102.0 109.0 109.0 111.0 109.0 110.0 109.0 108.0 109.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:136.50 stddev:18.69 (13.7%) stddev-sans-first:19.08 03:51:22 INFO - PROCESS | 24720 | Values: 152.0 124.0 124.0 177.0 124.0 126.0 147.0 124.0 153.0 124.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 03:51:22 INFO - PROCESS | 24720 | Values: 161.0 161.0 161.0 160.0 160.0 161.0 160.0 162.0 161.0 162.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#3] audio-fft Cycles:10 Average:83.10 Median:75.00 stddev:18.92 (25.2%) stddev-sans-first:19.78 03:51:22 INFO - PROCESS | 24720 | Values: 74.0 75.0 75.0 132.0 75.0 76.0 75.0 75.0 100.0 74.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:84.00 stddev:15.25 (18.2%) stddev-sans-first:15.77 03:51:22 INFO - PROCESS | 24720 | Values: 84.0 84.0 112.0 86.0 84.0 84.0 109.0 84.0 85.0 124.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 03:51:22 INFO - PROCESS | 24720 | Values: 117.0 118.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 03:51:22 INFO - PROCESS | 24720 | Values: 146.0 146.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 03:51:22 INFO - PROCESS | 24720 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.00 stddev:1.64 (3.2%) stddev-sans-first:1.51 03:51:22 INFO - PROCESS | 24720 | Values: 49.0 50.0 51.0 51.0 50.0 52.0 54.0 51.0 51.0 54.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:1.10 (1.8%) stddev-sans-first:1.09 03:51:22 INFO - PROCESS | 24720 | Values: 59.0 60.0 60.0 60.0 62.0 59.0 59.0 62.0 60.0 60.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:76.00 stddev:15.36 (20.2%) stddev-sans-first:16.15 03:51:22 INFO - PROCESS | 24720 | Values: 76.0 75.0 75.0 76.0 76.0 78.0 81.0 78.0 125.0 76.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:125.00 stddev:6.04 (4.8%) stddev-sans-first:6.40 03:51:22 INFO - PROCESS | 24720 | Values: 123.0 117.0 118.0 127.0 134.0 115.0 127.0 118.0 123.0 128.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.80 Median:156.00 stddev:1.93 (1.2%) stddev-sans-first:2.00 03:51:22 INFO - PROCESS | 24720 | Values: 156.0 156.0 157.0 157.0 155.0 154.0 152.0 152.0 153.0 156.0 03:51:22 INFO - PROCESS | 24720 | 03:51:22 INFO - PROCESS | 24720 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:2.02 (3.3%) stddev-sans-first:1.00 03:51:22 INFO - PROCESS | 24720 | Values: 67.0 63.0 62.0 61.0 62.0 61.0 62.0 60.0 61.0 60.0 03:51:22 INFO - PROCESS | 24720 | -------- Summary: end -------- 03:51:22 INFO - PROCESS | 24720 | 03:51:23 INFO - TEST-INFO | 24720: exit 0 03:51:23 INFO - TEST-OK | kraken | took 60648ms 03:51:23 INFO - SUITE-END | took 1173s 03:51:23 INFO - Completed test suite (00:19:33) 03:51:23 INFO - javascript benchmark 03:51:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3263.265765202829, 3278.70491655751, 3286.1127495576193, 3311.933761324775, 3324.717399021083, 5394.083005888999, 5476.014317422862, 5493.835184944448, 5495.093309603911, 5524.447555244478, 5478.945210547893, 5556.333240011194, 5562.499375056244, 5562.527185189264, 5586.190002449643, 4953.306537084811, 4985.302057711923, 5001.399832020163, 5021.046001259767, 5061.16490779021, 5012.649114561982, 5086.0, 5089.46560611136, 5091.643584949055, 5095.872603184913, 4914.705117692932, 4941.061198372316, 4961.950380496203, 4965.677230979974, 5087.56755675768, 5030.924536131961, 5040.34475518184, 5051.722155281458, 5065.4174769901565, 5091.974540127313, 4851.563359297645, 4884.731339776311, 4931.235658472907, 4959.7024178549045, 4984.0281145176705, 5299.284596579451, 5320.787168513255, 5323.920141197885, 5328.360596728378, 5392.91910621341, 5868.97065514674, 5882.882342353129, 5889.587728858982, 5898.882022359572, 5926.170336152942, 5736.2256095427, 5739.885202295931, 5793.623414478046, 5859.736311865933, 5963.970180149072, 5411.269478620376, 5465.098258787335, 5470.070088085035, 5471.398146203915, 5472.26124473195, 5780.0, 5963.403659634088, 5970.462658360769, 5977.043673012297, 5982.790602328898, 5757.021306377926, 5801.7099145042575, 5814.941850581525, 5874.32445268791, 5908.675022873697, 5915.674637894871, 5922.2597175353085, 5928.318243402064, 5948.821535353946, 5953.732082056318, 5608.803691870847, 5810.157527158538, 5928.407159284037, 5928.466438020599, 5936.5250779937505, 5867.14926335679, 5906.143609176731, 5908.645481271137, 5911.379305172895, 5933.1396947443245, 4544.2047641662575, 4545.227311357077, 4548.863534093916, 4549.27211646135, 4578.084383123389, 3991.1818077294083, 3993.7204395692024, 4029.2747305485295, 4075.9606300393802, 4115.814788334532, 5589.0, 5608.971955140199, 5612.07400778875, 5616.354119276336, 5634.3802181759975, 5916.467517923407, 5921.792737254175, 5932.881342373128, 5933.495652869468, 5952.851178720567, 5524.972375138099, 5577.8047768327915, 5587.776488940478, 5599.944000560024, 5633.0987042073075, 5120.590352771769, 5143.177091665316, 5151.098557752378, 5151.2530683050745, 5151.25306830515, 6135.294441139326, 6181.876362472725, 6182.505399568024, 6190.535709821781, 6198.473129784019, 5804.100364443518, 5837.241158649348, 5893.292804863444, 5899.764009439575, 5944.3164036136395, 6131.264248290234, 6135.11040899076, 6138.232720909887, 6140.355262697352, 6147.170132032297, 5570.108782594765, 5804.506616937608, 5809.477147056785, 5810.854728631818, 5812.18629391877, 5839.5036421904615, 5871.119332100219, 5924.348321684525, 5960.672163030901, 5965.6718880461995, 5799.0, 5828.679422631796, 5831.912521312099, 5842.094475356326, 5846.444587764156, 5651.717414129359, 5683.431656834284, 5775.306963164448, 5818.301803783573, 5838.678872661874, 5571.637843540157, 5653.321601407857, 5654.6324488908085, 5661.150827375762, 5684.147377893349, 5744.770209191585, 5754.9424505754405, 5789.53683705311, 5793.478586927196, 5797.971010144923, 5560.749766260609, 5605.719714014364, 5632.549396048388, 5643.181738647955, 5679.716014199356], "value": 5435.138722259004, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.514466144077, 651.3096118114797, 652.3378770547894, 652.8726898254839, 666.7766298290072, 33190.0, 36054.278914421695, 36061.27877442449, 36082.63917360826, 36087.097822554446, 47592.52407475924, 48667.0, 48776.51223487764, 52951.20573191405, 52963.205551916755, 9172.403793753401, 9298.53507324631, 9314.813703725922, 9315.72052838416, 9320.300977426687, 8407.5375854328, 9173.95413022934, 9262.305327100461, 9282.443053416782, 10813.945930270338, 10890.183236257273, 11325.660230193107, 11327.093832493421, 11334.433278336091, 11334.886651133465, 11176.664700059011, 11243.325400475998, 11247.100231981422, 11256.043236324882, 11267.830982535186, 8294.419390642657, 10703.197260205437, 10945.507452164671, 11144.777104457946, 11150.776984460266, 41242.0, 45310.32034519485, 45412.0, 45722.0, 45741.085178296584, 3555.3600351936643, 3581.9104522387147, 3609.747317687763, 3809.5619003814363, 4453.77731113443, 35306.0, 35373.46939795905, 35474.46788298152, 35805.28389432197, 35835.0, 4796.400449943757, 4871.805127794884, 4945.159322915077, 4951.579115775164, 4968.3292755477905, 398343.0, 399920.0, 401196.0, 401292.0, 401354.0, 38442.80778596089, 70953.64523177351, 71311.2868871315, 71577.2842271581, 71851.28148718551, 39249.0, 41321.58678413238, 41334.0, 41399.0, 41437.0, 14833.703325933637, 14860.405583776745, 14967.925160374347, 14972.251387430802, 14982.250887455803, 12281.877181228252, 12333.814992775115, 12340.321282329378, 12352.505899764088, 12373.443195056065, 8765.561721914006, 8919.910800892038, 9006.099390060941, 9154.542272886329, 9189.264858811279, 3510.8215382310887, 3516.9296614067575, 3535.7878527288444, 3566.1084728817796, 3609.801460919622, 1083.669480808361, 1086.0442810326863, 1117.407773879845, 1174.5360582570008, 1190.2798806721983, 35052.223216950064, 35386.0, 35421.64578354235, 35637.0, 35782.28435431277, 1184.8874356936078, 1207.5532053140219, 1256.4911210959576, 1261.9747605047849, 1264.0393301091053, 17774.111294435228, 18200.0, 18255.90872045631, 18282.177302021442, 18351.449456516326, 649306.2742803979, 658997.0, 661258.0, 661373.0, 662838.0, 63433.0, 63671.36328636654, 63704.68147659325, 63896.36103638904, 63986.0, 1321.73565286943, 1418.6098822823806, 1421.7394342994687, 1425.4298280687808, 1454.8472410396755, 34137.146571335914, 34665.65334346624, 34947.0, 35093.0, 35154.82422587871, 54191.0, 54347.18479222736, 54676.0, 54771.0, 54832.0, 463049.0, 463767.0, 464086.0, 465380.0, 465479.0, 27476.03833865805, 30224.84887575548, 30965.535516966815, 31277.374452511278, 32431.513527297582, 50891.49108508867, 52020.73989630028, 52709.73645131903, 53049.734751325996, 53559.0, 44756.55243447524, 44930.55069449264, 45149.77425112853, 45486.92226079798, 46224.537754622026, 44198.55801442073, 44518.0, 44544.33183502315, 45005.32492012688, 45545.0, 44584.55415445804, 45152.09695806131, 45690.77154614338, 45720.77139614281, 45959.0, 37277.62722372742, 39656.206875862896, 39678.404823927085, 39738.205235894544, 40395.79802100971, 29858.850705746332, 29863.55204671975, 30116.397672046875, 30500.389992199587, 30548.236294092825], "value": 20610.499588950348, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.64497821829656, 428.17148817039015, 429.83666206841394, 435.9041010977585, 440.929451287794, 18792.718109228357, 19624.116914738835, 19753.604927901433, 20049.59900801983, 20566.794332056674, 16881.24034418451, 16954.745678814827, 17130.657386852254, 17175.141242937865, 17296.135193240352, 5487.64330318529, 5705.515031222351, 5710.714464276791, 5720.399358067405, 5727.971360143214, 4153.190127925056, 5636.915446268309, 5693.146028095798, 5993.730282137292, 6307.810765677037, 6385.776497822577, 6392.721455708854, 6393.392627700361, 6401.679916004205, 6419.871602567946, 6287.968560157193, 6305.747770089191, 6424.775132870327, 6429.4534964528075, 6441.645709485976, 3820.732548721591, 5337.839864804062, 5688.203651488795, 5695.829125126253, 5708.20085188074, 23037.76962230373, 23095.0761969521, 23196.65205021926, 23326.53346933052, 23362.883185584134, 2323.8373313868037, 2502.0, 2521.8612976286295, 2538.708048574419, 3109.378124375134, 10428.426436545988, 10460.52927618259, 10468.319559228627, 10987.340759554372, 11029.007389334998, 1974.062320397814, 2024.6760518316999, 2026.311054241565, 2067.4211220858183, 2147.3665268745603, 56024.159637605466, 56175.0, 74308.0, 83595.58202209011, 92719.53640231823, 11311.60409385684, 11509.76980460386, 11562.479688414041, 11752.412379380996, 11838.230515016669, 16359.918200408922, 16444.424445144363, 16614.83385166133, 16626.83373166253, 16626.916865415595, 7759.72840950564, 7969.163237860057, 8062.072861620872, 8161.143079976636, 8225.83548329042, 7611.847763044819, 7618.4667073305445, 7657.6554055066545, 7749.263819937097, 7754.340881025063, 4945.307656928033, 4967.006598680278, 5017.498250174954, 5051.4695956924725, 5140.151874940668, 1832.2029916986153, 1845.4924895653803, 1858.1916866163263, 1961.6665166921657, 2034.52188735647, 966.21253678252, 1021.4126877045728, 1039.1998161415668, 1052.9473526323652, 1058.714147180257, 18629.534261643566, 18845.905770471334, 18906.218756248807, 18948.715769263472, 19025.334113306246, 1009.9040591143829, 1015.9441230732233, 1037.7872536130076, 1047.800917825626, 1103.2773533335678, 10484.423356715453, 10825.67522974312, 10841.728956775985, 10878.673639790819, 10956.34261944286, 477873.0, 479444.0, 480420.0, 487408.0, 488036.0, 60841.0, 77357.83963240556, 93762.53118734363, 94217.528912355, 94640.52679736557, 1023.9846402303972, 1034.4000479721744, 1053.073295499955, 1057.0750593230923, 1059.4467333725684, 16548.172591370625, 16970.151492425084, 17067.658646827244, 17179.226934788214, 17372.0, 39381.0, 40818.9795255121, 41483.37774933439, 42773.57226427696, 42889.0, 59819.0, 60032.0, 60202.0, 60793.0, 61499.0, 8501.787455313666, 8937.195652391316, 8992.595333210153, 9053.818923621358, 9219.631214751334, 9834.901650983398, 13058.542950996669, 13254.138480998705, 13298.135621184592, 13433.596992090253, 10713.410762407831, 10914.181436392144, 10991.175661825235, 11029.0, 11226.382548959886, 11174.385408802595, 11349.943250283695, 11438.82841757391, 11729.237599556001, 11784.116191285517, 11152.0, 11333.093352531943, 11334.546618135511, 11366.0, 11444.084473241954, 8699.0, 9043.00526942049, 9082.0, 9085.273178145864, 9139.086091390807, 10521.579136834442, 11062.502187401426, 11099.556017759522, 11127.499262533367, 11160.10719142483], "value": 9278.792740026813, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1468.8898332625056, 1524.1083965879957, 1569.8822588305877, 1780.8486278666312, 1855.6938105212641, 9342.486163261017, 9766.609335626576, 9791.216702663787, 9798.314118011724, 9804.362716423437, 9474.478903660312, 9626.326157169002, 9667.419954802717, 9672.371295865763, 9682.951585242065, 2539.555577773887, 2565.486902619474, 2567.627693984371, 2567.7303883092286, 2567.871606419681, 2515.2579988903317, 2564.192279431982, 2566.1275166443406, 2567.114345550782, 2569.3191304304373, 2476.839005464648, 2486.502699460106, 2514.735952724965, 2516.1319344826006, 2519.282004628681, 2386.570417324881, 2417.830751847371, 2440.2313271319567, 2449.155041510676, 2451.1053465485147, 2328.0571368595747, 2333.801626861719, 2335.976640233593, 2336.0, 2417.359399759065, 9346.345755797098, 9365.250779937589, 9469.195118414944, 9484.241260699197, 9699.224062075087, 5371.435999220064, 5486.561075113981, 5516.613837031409, 5517.172424136372, 5522.0, 3602.819859007039, 3625.474306225609, 3626.5829429615405, 3745.232227393378, 3745.3071181831447, 1251.3743128435783, 1263.5577547858268, 1269.1306455078302, 1278.9360531973364, 1293.2111412038648, 13730.65673358164, 13807.585772426843, 13854.0, 13875.0, 13885.722285554233, 4790.784414701347, 4868.318435419044, 4882.194437917704, 4884.902301954012, 4947.2579113133315, 10767.6231331903, 10813.351198928089, 10822.296550724177, 10833.29583577065, 10839.295445795999, 5365.463453654604, 5368.32895888014, 5368.91946620801, 5369.785208591613, 5372.355317361941, 4243.936340954888, 4685.508021657745, 4878.341423907798, 4903.166461701519, 4920.21276595743, 4315.741055536678, 4405.0, 4470.284754439274, 4496.3705081288645, 4516.16450956574, 1342.2215115233337, 1395.7278330725615, 1398.6153807702963, 1406.1633328169723, 1410.2102822419479, 855.7347222361088, 950.8811398575178, 1046.26238501856, 1048.4390850894888, 1049.5801679328329, 6161.44546990773, 6240.188775459162, 6370.171877655967, 6396.0, 6627.701753421107, 509.1065180608009, 510.3110800419404, 516.2591680937866, 516.5506009771523, 675.7161991963387, 4286.249906266391, 4366.541513141075, 4381.759003254851, 4396.230659634552, 4409.867703968886, 11531.077513798877, 11853.88146118545, 11858.584949526725, 11862.703432414259, 11992.100592455601, 11542.0, 11661.300321980709, 11683.941580292214, 11961.760764784656, 11969.820452693217, 8643.481391116553, 8738.86891696625, 8749.693760718472, 8769.123087691307, 8802.07578204292, 6527.086207930798, 6559.423865435429, 6591.868162636816, 6609.173853268341, 6768.578598494371, 10765.623203187852, 11047.558097676325, 11133.832992504957, 11171.162162837658, 11357.545698172311, 11240.887591123985, 11376.089912807121, 11424.543018279175, 11564.537418503165, 11576.36330001858, 7907.0, 8122.5126492410645, 8129.634166462405, 8389.874151887849, 8530.317574594143, 8854.380193386658, 9114.40756350835, 9275.118863707938, 9350.345475816628, 9612.75968100775, 8077.111517733162, 8105.067917189599, 8185.467944583583, 8224.3831712623, 8230.341572674051, 8125.0, 8148.592570371577, 8211.302039326723, 8244.958775206085, 8315.792105197419, 7612.048493938258, 7625.885611715939, 7688.961555192188, 7749.883751743616, 7752.689892404241, 5428.755705993319, 5458.208559758733, 5552.750126244409, 5610.7755689773585, 5727.198192253171, 5558.305211848519, 5633.521150702236, 5687.033204355353, 5841.474267315792, 5936.821895343147], "value": 4910.23135820254, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.18057744877, 354.3179379694089, 362.0387870204607, 363.35323124837765, 365.2074997255954, 5344.518993290603, 5423.0, 5459.344878614562, 5489.368722596902, 5506.889862202754, 5308.495692909168, 5330.706811125387, 5354.41101478837, 5389.78440862365, 5395.81114660987, 3826.789526576037, 4006.298897692907, 4230.407742915994, 4232.957670423287, 4249.19265339586, 4225.556316586745, 4232.3016702244095, 4237.364395340693, 4249.19265339586, 4261.573842615745, 4050.8784736457806, 4203.726757760751, 4219.367094935754, 4224.746515209082, 4229.830806767725, 4151.460310159675, 4235.555266697014, 4250.511191213003, 4260.105377870651, 4272.871813845559, 3743.6630703236565, 3776.9613356326954, 3781.357169281201, 3799.734018618698, 3802.8478860845535, 5196.974015129938, 5245.659032162936, 5261.316028916254, 5264.368275806889, 5273.578113750891, 4895.510448955111, 4907.214845624719, 4912.606991440676, 4932.383452068491, 4959.181735013718, 5600.0, 5635.21107045014, 5640.884668249407, 5645.237892884449, 5646.463585959327, 4058.533268674111, 4166.166766646683, 4173.165366926627, 4186.497620285555, 4204.684648651333, 5579.776808927638, 5705.172749951275, 5799.797007104731, 5838.0, 5883.294004719418, 4211.642010429086, 4291.184674911757, 4313.698041137153, 4313.956860431355, 4325.723911446068, 4331.956680433155, 4336.696431249782, 4338.804753786024, 4349.717268377545, 4372.628326592212, 4765.309030190603, 4815.277708343777, 4883.95116048835, 4897.608191344684, 4899.853004409873, 5637.43625637433, 5639.830805075854, 5653.9151912721345, 5665.555283402706, 5701.486866182064, 1477.9358861619617, 1498.2808252038865, 1505.1947208243537, 1507.4799194277957, 1507.6457032597332, 924.7965447601517, 956.2254573795109, 970.6214576315243, 975.7365511311906, 977.130353984954, 4901.117798796251, 4907.214845624683, 4928.753562321869, 4951.3810773653295, 4957.950420495749, 5143.691378517226, 5151.407588127338, 5153.561947234527, 5179.249008893689, 5218.225803226921, 4677.368555245066, 4981.402231732215, 4985.55130038291, 5007.069450481355, 5029.622778291643, 2739.0, 2769.6042922871975, 2781.3602871339704, 2783.930401739932, 2814.591884176783, 5320.494553017457, 5339.252504649352, 5351.866203344947, 5383.865403364947, 5439.945600543944, 5222.529972302511, 5254.7635356409055, 5505.697186654773, 5931.703414829241, 5987.79042733511, 6035.698215089228, 6053.667048312385, 6080.391960803884, 6087.1173679816175, 6090.543209259324, 5626.521745651666, 5834.18321435008, 5838.737256823369, 5842.824715258549, 5852.619579727304, 5707.914381284367, 5751.252337196305, 5751.654900705971, 5763.884722305446, 5780.248567686174, 5787.392323806108, 5862.443067908542, 6019.879602408015, 6051.485623721856, 6058.818235452944, 4103.548609652876, 4110.588941105865, 4124.52567954677, 4136.96575856036, 4142.1922725068325, 5700.0, 5770.163326317745, 5794.536437084938, 5873.823785286449, 5875.941240587539, 5632.098864181711, 5647.774089036557, 5653.519450846724, 5657.264555607745, 5681.289838770153, 5609.214709940693, 5619.269494965628, 5621.662700237999, 5626.959012582685, 5637.0, 5354.3574771027725, 5529.225908372911, 5535.612507124623, 5552.917181149637, 5587.497125258747, 5619.522340601095, 5641.448601634584, 5644.520215781705, 5654.6324488908085, 5670.773169073191, 5368.731563421735, 5536.837264174568, 5546.805861794979, 5551.833444996657, 5569.248151499496], "value": 4275.48912332034, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [847.6439895243993, 853.71827296992, 855.6662901468425, 861.4314552395418, 922.3313098003946, 15131.092134471939, 16197.10915899628, 16243.593910152253, 16245.756313655289, 16295.674086518262, 15805.525834224964, 16291.91854040731, 16311.510654680378, 16862.494125176232, 16917.407890723825, 702.5257950883159, 837.3426859914968, 852.4672079950046, 854.811941372897, 862.8317478091775, 860.311750599521, 860.9569521523899, 863.4732812984074, 864.0322838420946, 864.11859902899, 895.583553647557, 897.5512243878061, 897.9506127163004, 898.0166717444421, 898.4160295807744, 745.437194917839, 890.0832142892831, 892.9821403571925, 913.584319134792, 915.7847905742145, 653.1150291355241, 653.5980372071142, 654.1006116589689, 654.2639530528156, 656.6782276684438, 16358.754618680729, 16513.256903439375, 16529.917350413292, 16557.91721041387, 16590.66818663632, 14729.55811325662, 15299.235038248044, 15453.150076745775, 16542.586435339093, 16935.153242337834, 14150.976264066598, 14505.854941450556, 14599.270036498132, 14671.77992330116, 15139.0, 4794.280857871312, 4899.216125419915, 4928.901421971576, 4934.235193545006, 5059.595232381437, 66995.3300466994, 72780.63609681971, 75512.24487755107, 79724.0, 80447.0, 12605.05462090347, 13009.544665936646, 13200.009999250095, 13204.735905282032, 13454.32728363578, 24491.632625510632, 24495.38761530976, 24534.386640334134, 24549.877250613634, 24567.87716061444, 9321.347505674674, 9441.386309889835, 9501.772793233975, 9746.463944482979, 9820.852687209697, 5100.285959965608, 5181.4300426953005, 5187.147963470205, 5220.190870415092, 5358.624896257303, 3786.3941769316775, 3887.902802429962, 3892.649661530476, 3895.49358583388, 3904.1020565270146, 585.1515302810941, 606.6523594514342, 706.1878839334806, 714.0574441736961, 714.9561640005546, 685.3352248319122, 685.9245482996866, 724.2938135318022, 744.4677055905054, 753.6834529497625, 11221.495032723547, 11298.0, 11410.714732131763, 11568.652940411801, 11722.531098756124, 747.484235877243, 751.6692655231681, 762.3101093510382, 782.1357400072837, 782.8121250899743, 5072.82245121419, 5135.332406787168, 5152.613553983466, 5199.402068762065, 5246.921296180561, 129502.35248823884, 130183.0, 132570.0, 132697.33651331873, 135095.0, 86955.0, 95704.52147739356, 107450.46274768576, 108542.0, 109293.45353273183, 86726.0, 88522.0, 89240.0, 90423.0, 90818.0, 10660.520276587727, 10866.0, 10915.290506117077, 10966.122710183006, 10982.835257470984, 38372.6162738369, 41665.37501937413, 47153.292700608836, 51253.231201532755, 55540.0, 69492.0, 74702.62648686722, 81643.5917820407, 81670.0, 82333.17666823494, 13191.208527488381, 13881.791773123614, 14004.43982240699, 14342.713145737236, 14488.420463181355, 15977.520674379788, 16117.435889743803, 16153.75769363437, 16284.267207975432, 16950.067746274075, 11695.934637811897, 13987.720245594828, 14780.852191477947, 14924.55226343168, 16008.599785005003, 13848.584542463743, 14263.215523146326, 14847.034942728687, 14893.180875051976, 15470.767938480707, 14835.0, 14909.627259318604, 15314.0, 15332.846671533589, 15387.692246155239, 12251.38743062861, 12379.0, 12871.678208044874, 13098.410571524495, 13107.737845243233, 11823.467943942715, 12327.075469339656, 12331.383430828602, 12587.685307867376, 12666.936665316614], "value": 8217.842350591573, "unit": "score"}], "name": "dromaeo_css", "value": 7509.539515517469, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 102.0, 109.0, 109.0, 111.0, 109.0, 110.0, 109.0, 108.0, 109.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 124.0, 177.0, 124.0, 126.0, 147.0, 124.0, 153.0, 124.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 160.0, 161.0, 160.0, 162.0, 161.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 132.0, 75.0, 76.0, 75.0, 75.0, 100.0, 74.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 112.0, 86.0, 84.0, 84.0, 109.0, 84.0, 85.0, 124.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 51.0, 51.0, 50.0, 52.0, 54.0, 51.0, 51.0, 54.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 62.0, 59.0, 59.0, 62.0, 60.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 76.0, 76.0, 78.0, 81.0, 78.0, 125.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 117.0, 118.0, 127.0, 134.0, 115.0, 127.0, 118.0, 123.0, 128.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 156.0, 157.0, 157.0, 155.0, 154.0, 152.0, 152.0, 153.0, 156.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 62.0, 61.0, 62.0, 61.0, 62.0, 60.0, 61.0, 60.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1473.4999999999998, "alertThreshold": 2.0}]} 03:51:23 INFO - Return code: 0 03:51:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:51:23 ERROR - # TBPL SUCCESS # 03:51:23 INFO - Running post-action listener: _resource_record_post_action 03:51:23 INFO - ##### 03:51:23 INFO - ##### Finished run-tests step (success) 03:51:23 INFO - ##### 03:51:23 INFO - Running post-run listener: _resource_record_post_run 03:51:23 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 290816; Write bytes: 509751296; Read time: 48; Write time: 472320 03:51:23 INFO - TinderboxPrint: CPU usage
13.1% 03:51:23 INFO - TinderboxPrint: I/O read bytes / time
290,816 / 48 03:51:23 INFO - TinderboxPrint: I/O write bytes / time
509,751,296 / 472,320 03:51:23 INFO - TinderboxPrint: CPU idle
8,260.2 (86.9%) 03:51:23 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 03:51:23 INFO - TinderboxPrint: Swap in / out
0 / 0 03:51:23 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 259760128; Read time: 0; Write time: 285724 03:51:23 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249991168; Read time: 32; Write time: 186596 03:51:24 INFO - Running post-run listener: _upload_blobber_files 03:51:24 INFO - Blob upload gear active. 03:51:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:51:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:51:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:51:24 INFO - (blobuploader) - INFO - Open directory for files ... 03:51:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:51:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:51:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:25 INFO - (blobuploader) - INFO - Done attempting. 03:51:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:51:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:51:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:50 INFO - (blobuploader) - INFO - Done attempting. 03:51:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:51:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:51:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:51:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:51:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:51:51 INFO - (blobuploader) - INFO - Done attempting. 03:51:51 INFO - (blobuploader) - INFO - Iteration through files over. 03:51:51 INFO - Return code: 0 03:51:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:51:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:51:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fefba445b019c3177d5dd9b8d3e1539ae01ba921cf8ac757600d97a1b5c673f35487d897a0bbe7aff9b90b61e90970dfcec5f9f244baad6143379890216c9a49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00d9076c05dc2d518bacb620e29990124f72fe415cfba3e695c02ce7f4a13238e328df067cb57984b4929a1b2d3cc2fc1368922791c39b95a975d4d91a9c0e54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31a3b84111c76a1b1932f158cc8edb027b2292924f0d85e6902425d7ea5f5d184df405fc13744361903974fd7e6da4ec59ad6216cb639a385ce77a597c087d91"} 03:51:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:51:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:51:51 INFO - Contents: 03:51:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fefba445b019c3177d5dd9b8d3e1539ae01ba921cf8ac757600d97a1b5c673f35487d897a0bbe7aff9b90b61e90970dfcec5f9f244baad6143379890216c9a49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00d9076c05dc2d518bacb620e29990124f72fe415cfba3e695c02ce7f4a13238e328df067cb57984b4929a1b2d3cc2fc1368922791c39b95a975d4d91a9c0e54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31a3b84111c76a1b1932f158cc8edb027b2292924f0d85e6902425d7ea5f5d184df405fc13744361903974fd7e6da4ec59ad6216cb639a385ce77a597c087d91"} 03:51:51 INFO - Running post-run listener: copy_logs_to_upload_dir 03:51:51 INFO - Copying logs to upload dir... 03:51:51 INFO - mkdir: /builds/slave/test/build/upload/logs 03:51:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.654162 ========= master_lag: 128.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 6 secs) (at 2016-07-26 03:53:58.812417) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-26 03:53:58.820425) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300 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/fefba445b019c3177d5dd9b8d3e1539ae01ba921cf8ac757600d97a1b5c673f35487d897a0bbe7aff9b90b61e90970dfcec5f9f244baad6143379890216c9a49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00d9076c05dc2d518bacb620e29990124f72fe415cfba3e695c02ce7f4a13238e328df067cb57984b4929a1b2d3cc2fc1368922791c39b95a975d4d91a9c0e54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31a3b84111c76a1b1932f158cc8edb027b2292924f0d85e6902425d7ea5f5d184df405fc13744361903974fd7e6da4ec59ad6216cb639a385ce77a597c087d91"} build_url:https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011280 build_url: 'https://queue.taskcluster.net/v1/task/VONsMXOgSV2_Tp-kC6BFNw/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/fefba445b019c3177d5dd9b8d3e1539ae01ba921cf8ac757600d97a1b5c673f35487d897a0bbe7aff9b90b61e90970dfcec5f9f244baad6143379890216c9a49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00d9076c05dc2d518bacb620e29990124f72fe415cfba3e695c02ce7f4a13238e328df067cb57984b4929a1b2d3cc2fc1368922791c39b95a975d4d91a9c0e54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/31a3b84111c76a1b1932f158cc8edb027b2292924f0d85e6902425d7ea5f5d184df405fc13744361903974fd7e6da4ec59ad6216cb639a385ce77a597c087d91"}' ========= master_lag: 1.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-26 03:53:59.908556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 mins, 3 secs) (at 2016-07-26 03:53:59.912328) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1469529010.11366-879108300 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 303.87 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 mins, 3 secs) (at 2016-07-26 03:59:03.789029) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 03:59:03.789534) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-26 03:59:04.141031) ========= ========= Total master_lag: 433.26 =========