builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-012 starttime: 1469686556.59 results: success (0) buildid: 20160727203324 builduid: aee55d62853c418883c7ed753f797617 revision: cff908fa53dc70ded390832f107c3fcd1262155b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:56.595085) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:56.595552) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:56.595924) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:56.611728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:56.612261) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-27 23:15:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.7M=0s 2016-07-27 23:15:56 (70.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120672 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:56.750760) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:56.751474) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023115 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:56.801526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 23:15:56.802166) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cff908fa53dc70ded390832f107c3fcd1262155b --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 cff908fa53dc70ded390832f107c3fcd1262155b --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=2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-27 23:15:56,987 truncating revision to first 12 chars 2016-07-27 23:15:56,987 Setting DEBUG logging. 2016-07-27 23:15:56,987 attempt 1/10 2016-07-27 23:15:56,987 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cff908fa53dc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 23:15:58,239 unpacking tar archive at: mozilla-aurora-cff908fa53dc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.637779 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 23:15:58.457326) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:58.458039) ========= script_repo_revision: cff908fa53dc70ded390832f107c3fcd1262155b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:58.458881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:58.459531) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 23:15:58.471838) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-27 23:15:58.472148) ========= /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=2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:15:58 INFO - MultiFileLogger online at 20160727 23:15:58 in /builds/slave/test 23:15:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:15:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:15:58 INFO - {'append_to_log': False, 23:15:58 INFO - 'base_work_dir': '/builds/slave/test', 23:15:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:15:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:15:58 INFO - 'branch': 'Mozilla-Aurora', 23:15:58 INFO - 'buildbot_json_path': 'buildprops.json', 23:15:58 INFO - 'config_files': ('talos/linux_config.py',), 23:15:58 INFO - 'default_actions': ('clobber', 23:15:58 INFO - 'read-buildbot-config', 23:15:58 INFO - 'download-and-extract', 23:15:58 INFO - 'populate-webroot', 23:15:58 INFO - 'create-virtualenv', 23:15:58 INFO - 'install', 23:15:58 INFO - 'run-tests'), 23:15:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:15:58 INFO - 'download_minidump_stackwalk': True, 23:15:58 INFO - 'download_symbols': 'ondemand', 23:15:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:15:58 INFO - 'tooltool.py': '/tools/tooltool.py', 23:15:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:15:58 INFO - '/tools/misc-python/virtualenv.py')}, 23:15:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:15:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:15:58 INFO - 'installer_path': 'installer.exe', 23:15:58 INFO - 'log_level': 'info', 23:15:58 INFO - 'log_name': 'talos', 23:15:58 INFO - 'log_to_console': True, 23:15:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:15:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:15:58 INFO - 'opt_config_files': (), 23:15:58 INFO - 'pip_index': False, 23:15:58 INFO - 'sps_profile': False, 23:15:58 INFO - 'sps_profile_interval': 0, 23:15:58 INFO - 'suite': 'dromaeojs', 23:15:58 INFO - 'system_bits': '32', 23:15:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:15:58 INFO - 'title': 'talos-linux64-ix-012', 23:15:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:15:58 INFO - 'use_talos_json': True, 23:15:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:15:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:15:58 INFO - 'work_dir': 'build'} 23:15:58 INFO - ##### 23:15:58 INFO - ##### Running clobber step. 23:15:58 INFO - ##### 23:15:58 INFO - Running pre-action listener: _resource_record_pre_action 23:15:58 INFO - Running main action method: clobber 23:15:58 INFO - rmtree: /builds/slave/test/build 23:15:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:15:59 INFO - Running post-action listener: _resource_record_post_action 23:15:59 INFO - ##### 23:15:59 INFO - ##### Finished clobber step (success) 23:15:59 INFO - ##### 23:15:59 INFO - ##### 23:15:59 INFO - ##### Running read-buildbot-config step. 23:15:59 INFO - ##### 23:15:59 INFO - Running pre-action listener: _resource_record_pre_action 23:15:59 INFO - Running main action method: read_buildbot_config 23:15:59 INFO - Using buildbot properties: 23:15:59 INFO - { 23:15:59 INFO - "project": "", 23:15:59 INFO - "product": "firefox", 23:15:59 INFO - "script_repo_revision": "production", 23:15:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 23:15:59 INFO - "repository": "", 23:15:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 23:15:59 INFO - "buildid": "20160727203324", 23:15:59 INFO - "pgo_build": "True", 23:15:59 INFO - "basedir": "/builds/slave/test", 23:15:59 INFO - "buildnumber": 638, 23:15:59 INFO - "slavename": "talos-linux64-ix-012", 23:15:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:15:59 INFO - "platform": "ubuntu64_hw", 23:15:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 23:15:59 INFO - "branch": "mozilla-aurora", 23:15:59 INFO - "repo_path": "releases/mozilla-aurora", 23:15:59 INFO - "revision": "cff908fa53dc70ded390832f107c3fcd1262155b", 23:15:59 INFO - "stage_platform": "linux64-pgo", 23:15:59 INFO - "builduid": "aee55d62853c418883c7ed753f797617", 23:15:59 INFO - "slavebuilddir": "test" 23:15:59 INFO - } 23:15:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 23:15:59 INFO - Running post-action listener: _resource_record_post_action 23:15:59 INFO - ##### 23:15:59 INFO - ##### Finished read-buildbot-config step (success) 23:15:59 INFO - ##### 23:15:59 INFO - ##### 23:15:59 INFO - ##### Running download-and-extract step. 23:15:59 INFO - ##### 23:15:59 INFO - Running pre-action listener: _resource_record_pre_action 23:15:59 INFO - Running main action method: download_and_extract 23:15:59 INFO - mkdir: /builds/slave/test/build/tests 23:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:15:59 INFO - https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:15:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 23:15:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 23:15:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:15:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:00 INFO - Downloaded 1361 bytes. 23:16:00 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 23:16:00 INFO - Using the following test package requirements: 23:16:00 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 23:16:00 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:16:00 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 23:16:00 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:16:00 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 23:16:00 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:16:00 INFO - u'jsshell-linux-x86_64.zip'], 23:16:00 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:16:00 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 23:16:00 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 23:16:00 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:16:00 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 23:16:00 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:16:00 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 23:16:00 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:16:00 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 23:16:00 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 23:16:00 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 23:16:00 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:00 INFO - https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:16:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 23:16:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 23:16:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:01 INFO - Downloaded 24494128 bytes. 23:16:01 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'] 23:16:01 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 23:16:03 INFO - Return code: 0 23:16:03 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 23:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:03 INFO - https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:16:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 23:16:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 23:16:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:06 INFO - Downloaded 24494128 bytes. 23:16:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:16:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:16:07 INFO - Return code: 0 23:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:07 INFO - https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:16:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 23:16:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 23:16:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:08 INFO - Downloaded 10833369 bytes. 23:16:08 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'] 23:16:08 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 23:16:08 INFO - Return code: 0 23:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:08 INFO - https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:16:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 23:16:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 23:16:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Es3opUeJQsatstOdhznFXw/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 23:16:12 INFO - Downloaded 63660389 bytes. 23:16:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 23:16:12 INFO - mkdir: /builds/slave/test/properties 23:16:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:16:12 INFO - Writing to file /builds/slave/test/properties/build_url 23:16:12 INFO - Contents: 23:16:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 23:16:13 INFO - Running post-action listener: _resource_record_post_action 23:16:13 INFO - Running post-action listener: set_extra_try_arguments 23:16:13 INFO - ##### 23:16:13 INFO - ##### Finished download-and-extract step (success) 23:16:13 INFO - ##### 23:16:13 INFO - ##### 23:16:13 INFO - ##### Running populate-webroot step. 23:16:13 INFO - ##### 23:16:13 INFO - Running pre-action listener: _resource_record_pre_action 23:16:13 INFO - Running main action method: populate_webroot 23:16:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:16:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:16:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:16:13 INFO - '--tppagecycles', 23:16:13 INFO - '7'], 23:16:13 INFO - 'tests': ['tsvgm']}}, 23:16:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:16:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:16:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:16:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:16:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:16:13 INFO - 'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:16:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:16:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:16:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:16:13 INFO - 'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['damp', 'tps']}, 23:16:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:16:13 INFO - 'tests': ['damp', 'tps']}, 23:16:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['dromaeo_dom']}, 23:16:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:16:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['a11yr', 23:16:13 INFO - 'ts_paint', 23:16:13 INFO - 'tpaint', 23:16:13 INFO - 'sessionrestore', 23:16:13 INFO - 'sessionrestore_no_auto_restore', 23:16:13 INFO - 'tabpaint']}, 23:16:13 INFO - 'other-e10s': {'tests': ['a11yr', 23:16:13 INFO - 'ts_paint', 23:16:13 INFO - 'tpaint', 23:16:13 INFO - 'sessionrestore', 23:16:13 INFO - 'sessionrestore_no_auto_restore', 23:16:13 INFO - 'tabpaint']}, 23:16:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:16:13 INFO - 'ts_paint', 23:16:13 INFO - 'tpaint', 23:16:13 INFO - 'sessionrestore', 23:16:13 INFO - 'sessionrestore_no_auto_restore', 23:16:13 INFO - 'tabpaint']}, 23:16:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:16:13 INFO - 'ts_paint', 23:16:13 INFO - 'tpaint', 23:16:13 INFO - 'sessionrestore', 23:16:13 INFO - 'sessionrestore_no_auto_restore', 23:16:13 INFO - 'tabpaint']}, 23:16:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['a11yr', 23:16:13 INFO - 'ts_paint', 23:16:13 INFO - 'tpaint', 23:16:13 INFO - 'sessionrestore', 23:16:13 INFO - 'sessionrestore_no_auto_restore', 23:16:13 INFO - 'tabpaint']}, 23:16:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['a11yr', 23:16:13 INFO - 'ts_paint', 23:16:13 INFO - 'tpaint', 23:16:13 INFO - 'sessionrestore', 23:16:13 INFO - 'sessionrestore_no_auto_restore', 23:16:13 INFO - 'tabpaint']}, 23:16:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['tsvgx', 23:16:13 INFO - 'tsvgr_opacity', 23:16:13 INFO - 'tart', 23:16:13 INFO - 'tscrollx', 23:16:13 INFO - 'cart']}, 23:16:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:16:13 INFO - 'tsvgr_opacity', 23:16:13 INFO - 'tart', 23:16:13 INFO - 'tscrollx', 23:16:13 INFO - 'cart']}, 23:16:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:16:13 INFO - 'talos_options': ['--disable-e10s'], 23:16:13 INFO - 'tests': ['tp5o']}, 23:16:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:16:13 INFO - 'tests': ['tp5o']}, 23:16:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:16:13 INFO - 'talos_options': ['--disable-e10s', 23:16:13 INFO - '--xperf_path', 23:16:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:16:13 INFO - 'tests': ['tp5n']}, 23:16:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:16:13 INFO - 'talos_options': ['--xperf_path', 23:16:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:16:13 INFO - 'tests': ['tp5n']}}, 23:16:13 INFO - 'talos.zip': {'path': '', 23:16:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:16:13 INFO - Running post-action listener: _resource_record_post_action 23:16:13 INFO - ##### 23:16:13 INFO - ##### Finished populate-webroot step (success) 23:16:13 INFO - ##### 23:16:13 INFO - ##### 23:16:13 INFO - ##### Running create-virtualenv step. 23:16:13 INFO - ##### 23:16:13 INFO - Running pre-action listener: _resource_record_pre_action 23:16:13 INFO - Running main action method: create_virtualenv 23:16:13 INFO - Creating virtualenv /builds/slave/test/build/venv 23:16:13 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 23:16:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:16:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:16:13 INFO - Using real prefix '/usr' 23:16:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:16:13 INFO - Installing distribute.............................................................................................................................................................................................done. 23:16:15 INFO - Installing pip.................done. 23:16:15 INFO - Return code: 0 23:16:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:16: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')]} 23:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16: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')]} 23:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16: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', 'pip>=1.5'] in /builds/slave/test/build 23:16: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 pip>=1.5 23:16:15 INFO - Using env: {'DISPLAY': ':0', 23:16:15 INFO - 'HOME': '/home/cltbld', 23:16:15 INFO - 'LANG': 'en_US.UTF-8', 23:16:15 INFO - 'LANGUAGE': 'en_US:en', 23:16:15 INFO - 'LOGNAME': 'cltbld', 23:16:15 INFO - 'MAIL': '/var/mail/cltbld', 23:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:15 INFO - 'MOZ_NO_REMOTE': '1', 23:16:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:15 INFO - 'NO_EM_RESTART': '1', 23:16:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:15 INFO - 'PWD': '/builds/slave/test', 23:16:15 INFO - 'SHELL': '/bin/bash', 23:16:15 INFO - 'SHLVL': '1', 23:16:15 INFO - 'TERM': 'linux', 23:16:15 INFO - 'TMOUT': '86400', 23:16:15 INFO - 'USER': 'cltbld', 23:16:15 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:15 INFO - '_': '/tools/buildbot/bin/python'} 23:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:15 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 23:16:15 INFO - Cleaning up... 23:16:15 INFO - Return code: 0 23:16:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:16: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')]} 23:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16: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')]} 23:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16: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', '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 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16: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', 'psutil>=3.1.1'] in /builds/slave/test/build 23:16: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 psutil>=3.1.1 23:16:15 INFO - Using env: {'DISPLAY': ':0', 23:16:15 INFO - 'HOME': '/home/cltbld', 23:16:15 INFO - 'LANG': 'en_US.UTF-8', 23:16:15 INFO - 'LANGUAGE': 'en_US:en', 23:16:15 INFO - 'LOGNAME': 'cltbld', 23:16:15 INFO - 'MAIL': '/var/mail/cltbld', 23:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:15 INFO - 'MOZ_NO_REMOTE': '1', 23:16:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:15 INFO - 'NO_EM_RESTART': '1', 23:16:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:15 INFO - 'PWD': '/builds/slave/test', 23:16:15 INFO - 'SHELL': '/bin/bash', 23:16:15 INFO - 'SHLVL': '1', 23:16:15 INFO - 'TERM': 'linux', 23:16:15 INFO - 'TMOUT': '86400', 23:16:15 INFO - 'USER': 'cltbld', 23:16:15 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:15 INFO - '_': '/tools/buildbot/bin/python'} 23:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:16 INFO - Downloading/unpacking psutil>=3.1.1 23:16:16 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 23:16:16 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 23:16:16 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 23:16:16 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 23:16:16 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 23:16:16 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 23:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:16:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:16:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:16:18 INFO - Installing collected packages: psutil 23:16:18 INFO - Running setup.py install for psutil 23:16:18 INFO - building 'psutil._psutil_linux' extension 23:16:18 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 23:16:18 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 23:16:18 INFO - building 'psutil._psutil_posix' extension 23:16:18 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 23:16:18 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 23:16:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:16:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:16:18 INFO - Successfully installed psutil 23:16:18 INFO - Cleaning up... 23:16:18 INFO - Return code: 0 23:16:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:16: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')]} 23:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16: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')]} 23:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:16: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 mozsystemmonitor==0.3 23:16:18 INFO - Using env: {'DISPLAY': ':0', 23:16:18 INFO - 'HOME': '/home/cltbld', 23:16:18 INFO - 'LANG': 'en_US.UTF-8', 23:16:18 INFO - 'LANGUAGE': 'en_US:en', 23:16:18 INFO - 'LOGNAME': 'cltbld', 23:16:18 INFO - 'MAIL': '/var/mail/cltbld', 23:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:18 INFO - 'MOZ_NO_REMOTE': '1', 23:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:18 INFO - 'NO_EM_RESTART': '1', 23:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:18 INFO - 'PWD': '/builds/slave/test', 23:16:18 INFO - 'SHELL': '/bin/bash', 23:16:18 INFO - 'SHLVL': '1', 23:16:18 INFO - 'TERM': 'linux', 23:16:18 INFO - 'TMOUT': '86400', 23:16:18 INFO - 'USER': 'cltbld', 23:16:18 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:18 INFO - '_': '/tools/buildbot/bin/python'} 23:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:16: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 23:16: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 23:16: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 23:16: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 23:16: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 23:16: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 23:16:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:16:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:16:20 INFO - Installing collected packages: mozsystemmonitor 23:16:20 INFO - Running setup.py install for mozsystemmonitor 23:16:20 INFO - Successfully installed mozsystemmonitor 23:16:20 INFO - Cleaning up... 23:16:20 INFO - Return code: 0 23:16:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:16:20 INFO - Using env: {'DISPLAY': ':0', 23:16:20 INFO - 'HOME': '/home/cltbld', 23:16:20 INFO - 'LANG': 'en_US.UTF-8', 23:16:20 INFO - 'LANGUAGE': 'en_US:en', 23:16:20 INFO - 'LOGNAME': 'cltbld', 23:16:20 INFO - 'MAIL': '/var/mail/cltbld', 23:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:20 INFO - 'MOZ_NO_REMOTE': '1', 23:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:20 INFO - 'NO_EM_RESTART': '1', 23:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:20 INFO - 'PWD': '/builds/slave/test', 23:16:20 INFO - 'SHELL': '/bin/bash', 23:16:20 INFO - 'SHLVL': '1', 23:16:20 INFO - 'TERM': 'linux', 23:16:20 INFO - 'TMOUT': '86400', 23:16:20 INFO - 'USER': 'cltbld', 23:16:20 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:20 INFO - '_': '/tools/buildbot/bin/python'} 23:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:21 INFO - Downloading/unpacking jsonschema==2.5.1 23:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:16:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:23 INFO - Downloading functools32-3.2.3-2.tar.gz 23:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:16:23 INFO - warning: no files found matching '*.txt' 23:16:23 INFO - no previously-included directories found matching 'build' 23:16:23 INFO - no previously-included directories found matching 'dist' 23:16:23 INFO - no previously-included directories found matching '.git*' 23:16:23 INFO - Installing collected packages: jsonschema, functools32 23:16:23 INFO - Running setup.py install for functools32 23:16:23 INFO - warning: no files found matching '*.txt' 23:16:23 INFO - no previously-included directories found matching 'build' 23:16:23 INFO - no previously-included directories found matching 'dist' 23:16:23 INFO - no previously-included directories found matching '.git*' 23:16:23 INFO - Successfully installed jsonschema functools32 23:16:23 INFO - Cleaning up... 23:16:23 INFO - Return code: 0 23:16:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:16:23 INFO - Using env: {'DISPLAY': ':0', 23:16:23 INFO - 'HOME': '/home/cltbld', 23:16:23 INFO - 'LANG': 'en_US.UTF-8', 23:16:23 INFO - 'LANGUAGE': 'en_US:en', 23:16:23 INFO - 'LOGNAME': 'cltbld', 23:16:23 INFO - 'MAIL': '/var/mail/cltbld', 23:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:23 INFO - 'MOZ_NO_REMOTE': '1', 23:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:23 INFO - 'NO_EM_RESTART': '1', 23:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:23 INFO - 'PWD': '/builds/slave/test', 23:16:23 INFO - 'SHELL': '/bin/bash', 23:16:23 INFO - 'SHLVL': '1', 23:16:23 INFO - 'TERM': 'linux', 23:16:23 INFO - 'TMOUT': '86400', 23:16:23 INFO - 'USER': 'cltbld', 23:16:23 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:23 INFO - '_': '/tools/buildbot/bin/python'} 23:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:23 INFO - Downloading/unpacking blobuploader==1.2.4 23:16:23 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 23:16:23 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 23:16:23 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 23:16:23 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 23:16:23 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 23:16:23 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 23:16:25 INFO - Downloading blobuploader-1.2.4.tar.gz 23:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:16:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:16:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:16: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 23:16: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 23:16: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 23:16: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 23:16: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 23:16: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 23:16:26 INFO - Downloading docopt-0.6.1.tar.gz 23:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:16:26 INFO - Installing collected packages: blobuploader, requests, docopt 23:16:26 INFO - Running setup.py install for blobuploader 23:16:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:16:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:16:26 INFO - Running setup.py install for requests 23:16:26 INFO - Running setup.py install for docopt 23:16:26 INFO - Successfully installed blobuploader requests docopt 23:16:26 INFO - Cleaning up... 23:16:26 INFO - Return code: 0 23:16:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:26 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 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16:26 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 23:16:26 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 23:16:26 INFO - Using env: {'DISPLAY': ':0', 23:16:26 INFO - 'HOME': '/home/cltbld', 23:16:26 INFO - 'LANG': 'en_US.UTF-8', 23:16:26 INFO - 'LANGUAGE': 'en_US:en', 23:16:26 INFO - 'LOGNAME': 'cltbld', 23:16:26 INFO - 'MAIL': '/var/mail/cltbld', 23:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:26 INFO - 'MOZ_NO_REMOTE': '1', 23:16:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:26 INFO - 'NO_EM_RESTART': '1', 23:16:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:26 INFO - 'PWD': '/builds/slave/test', 23:16:26 INFO - 'SHELL': '/bin/bash', 23:16:26 INFO - 'SHLVL': '1', 23:16:26 INFO - 'TERM': 'linux', 23:16:26 INFO - 'TMOUT': '86400', 23:16:26 INFO - 'USER': 'cltbld', 23:16:26 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:26 INFO - '_': '/tools/buildbot/bin/python'} 23:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:16:27 INFO - Running setup.py (path:/tmp/pip-uVPmLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:16:27 INFO - Running setup.py (path:/tmp/pip-V7IDXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:16:27 INFO - Running setup.py (path:/tmp/pip-o4fYfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:16:27 INFO - Running setup.py (path:/tmp/pip-CWTcS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:16:27 INFO - Running setup.py (path:/tmp/pip-6DwEwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:16:27 INFO - Running setup.py (path:/tmp/pip-Ahrpu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:16:27 INFO - Running setup.py (path:/tmp/pip-GY6b_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:16:27 INFO - Running setup.py (path:/tmp/pip-INerIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:16:27 INFO - Running setup.py (path:/tmp/pip-2GGKE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:16:27 INFO - Running setup.py (path:/tmp/pip-7mwSqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:16:27 INFO - Running setup.py (path:/tmp/pip-UpUPre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:16:27 INFO - Running setup.py (path:/tmp/pip-5b6vu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:16:28 INFO - Running setup.py (path:/tmp/pip-drNUVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:16:28 INFO - Running setup.py (path:/tmp/pip-2i4sT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:16:28 INFO - Running setup.py (path:/tmp/pip-xMrLc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:16:28 INFO - Running setup.py (path:/tmp/pip-z8DpKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:16:28 INFO - Running setup.py (path:/tmp/pip-iKQMZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:16:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:16:28 INFO - Running setup.py install for manifestparser 23:16:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:16:28 INFO - Running setup.py install for mozcrash 23:16:28 INFO - Running setup.py install for mozdebug 23:16:28 INFO - Running setup.py install for mozdevice 23:16:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:16:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:16:28 INFO - Running setup.py install for mozfile 23:16:28 INFO - Running setup.py install for mozhttpd 23:16:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Running setup.py install for mozinfo 23:16:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Running setup.py install for mozInstall 23:16:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Running setup.py install for mozleak 23:16:29 INFO - Running setup.py install for mozlog 23:16:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Running setup.py install for moznetwork 23:16:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Running setup.py install for mozprocess 23:16:29 INFO - Running setup.py install for mozprofile 23:16:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Running setup.py install for mozrunner 23:16:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:16:29 INFO - Running setup.py install for mozscreenshot 23:16:29 INFO - Running setup.py install for moztest 23:16:30 INFO - Running setup.py install for mozversion 23:16:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:16:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:16:30 INFO - Cleaning up... 23:16:30 INFO - Return code: 0 23:16:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:30 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 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16:30 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 23:16:30 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 23:16:30 INFO - Using env: {'DISPLAY': ':0', 23:16:30 INFO - 'HOME': '/home/cltbld', 23:16:30 INFO - 'LANG': 'en_US.UTF-8', 23:16:30 INFO - 'LANGUAGE': 'en_US:en', 23:16:30 INFO - 'LOGNAME': 'cltbld', 23:16:30 INFO - 'MAIL': '/var/mail/cltbld', 23:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:30 INFO - 'MOZ_NO_REMOTE': '1', 23:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:30 INFO - 'NO_EM_RESTART': '1', 23:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:30 INFO - 'PWD': '/builds/slave/test', 23:16:30 INFO - 'SHELL': '/bin/bash', 23:16:30 INFO - 'SHLVL': '1', 23:16:30 INFO - 'TERM': 'linux', 23:16:30 INFO - 'TMOUT': '86400', 23:16:30 INFO - 'USER': 'cltbld', 23:16:30 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:30 INFO - '_': '/tools/buildbot/bin/python'} 23:16:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:16:30 INFO - Running setup.py (path:/tmp/pip-ktBXO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:16:30 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)) 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:16:30 INFO - Running setup.py (path:/tmp/pip-7Dtedy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:16:30 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)) 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:16:30 INFO - Running setup.py (path:/tmp/pip-AbN6sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:16:30 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)) 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:16:30 INFO - Running setup.py (path:/tmp/pip-sweSN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:16:30 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)) 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:16:30 INFO - Running setup.py (path:/tmp/pip-NoUxcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:16:30 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)) 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:16:30 INFO - Running setup.py (path:/tmp/pip-ufhQ5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:16:30 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)) 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:16:30 INFO - Running setup.py (path:/tmp/pip-MY6FKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:16:30 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)) 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:16:30 INFO - Running setup.py (path:/tmp/pip-9i_R8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:16:30 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)) 23:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:16:30 INFO - Running setup.py (path:/tmp/pip-NIljUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:16:31 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)) 23:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:16:31 INFO - Running setup.py (path:/tmp/pip-edqBEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:16:31 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)) 23:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:16:31 INFO - Running setup.py (path:/tmp/pip-hOwCwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:16:31 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)) 23:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:16:31 INFO - Running setup.py (path:/tmp/pip-i5iG_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:16:31 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)) 23:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:16:31 INFO - Running setup.py (path:/tmp/pip-GI99sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:16:31 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)) 23:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:16:31 INFO - Running setup.py (path:/tmp/pip-4mXJ8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:16:31 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)) 23:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:16:31 INFO - Running setup.py (path:/tmp/pip-Nn5mpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:16:31 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)) 23:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:16:31 INFO - Running setup.py (path:/tmp/pip-kTkOUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:16:31 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)) 23:16:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:16:31 INFO - Running setup.py (path:/tmp/pip-WKjL4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:16:31 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)) 23:16:31 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)) 23:16:31 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)) 23:16:31 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)) 23:16:31 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)) 23:16:31 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)) 23:16:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:16: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 23:16: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 23:16: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 23:16: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 23:16: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 23:16: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 23:16:33 INFO - Downloading blessings-1.6.tar.gz 23:16:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:16:33 INFO - Installing collected packages: blessings 23:16:33 INFO - Running setup.py install for blessings 23:16:33 INFO - Successfully installed blessings 23:16:33 INFO - Cleaning up... 23:16:33 INFO - Return code: 0 23:16:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:16:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:16:33 INFO - Reading from file tmpfile_stdout 23:16:33 INFO - Current package versions: 23:16:33 INFO - argparse == 1.2.1 23:16:33 INFO - blessings == 1.6 23:16:33 INFO - blobuploader == 1.2.4 23:16:33 INFO - docopt == 0.6.1 23:16:33 INFO - functools32 == 3.2.3-2 23:16:33 INFO - jsonschema == 2.5.1 23:16:33 INFO - manifestparser == 1.1 23:16:33 INFO - mozInstall == 1.12 23:16:33 INFO - mozcrash == 0.17 23:16:33 INFO - mozdebug == 0.1 23:16:33 INFO - mozdevice == 0.48 23:16:33 INFO - mozfile == 1.2 23:16:33 INFO - mozhttpd == 0.7 23:16:33 INFO - mozinfo == 0.9 23:16:33 INFO - mozleak == 0.1 23:16:33 INFO - mozlog == 3.2 23:16:33 INFO - moznetwork == 0.27 23:16:33 INFO - mozprocess == 0.23 23:16:33 INFO - mozprofile == 0.28 23:16:33 INFO - mozrunner == 6.12 23:16:33 INFO - mozscreenshot == 0.1 23:16:34 INFO - mozsystemmonitor == 0.3 23:16:34 INFO - moztest == 0.7 23:16:34 INFO - mozversion == 1.4 23:16:34 INFO - psutil == 3.1.1 23:16:34 INFO - requests == 1.2.3 23:16:34 INFO - wsgiref == 0.1.2 23:16:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:16:34 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')]} 23:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:34 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')]} 23:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:34 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 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16:34 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 23:16:34 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 23:16:34 INFO - Using env: {'DISPLAY': ':0', 23:16:34 INFO - 'HOME': '/home/cltbld', 23:16:34 INFO - 'LANG': 'en_US.UTF-8', 23:16:34 INFO - 'LANGUAGE': 'en_US:en', 23:16:34 INFO - 'LOGNAME': 'cltbld', 23:16:34 INFO - 'MAIL': '/var/mail/cltbld', 23:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:34 INFO - 'MOZ_NO_REMOTE': '1', 23:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:34 INFO - 'NO_EM_RESTART': '1', 23:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:34 INFO - 'PWD': '/builds/slave/test', 23:16:34 INFO - 'SHELL': '/bin/bash', 23:16:34 INFO - 'SHLVL': '1', 23:16:34 INFO - 'TERM': 'linux', 23:16:34 INFO - 'TMOUT': '86400', 23:16:34 INFO - 'USER': 'cltbld', 23:16:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:34 INFO - '_': '/tools/buildbot/bin/python'} 23:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 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)) 23:16:34 INFO - Cleaning up... 23:16:34 INFO - Return code: 0 23:16:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:16:34 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')]} 23:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:34 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')]} 23:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:34 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 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16:34 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 23:16:34 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 23:16:34 INFO - Using env: {'DISPLAY': ':0', 23:16:34 INFO - 'HOME': '/home/cltbld', 23:16:34 INFO - 'LANG': 'en_US.UTF-8', 23:16:34 INFO - 'LANGUAGE': 'en_US:en', 23:16:34 INFO - 'LOGNAME': 'cltbld', 23:16:34 INFO - 'MAIL': '/var/mail/cltbld', 23:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:34 INFO - 'MOZ_NO_REMOTE': '1', 23:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:34 INFO - 'NO_EM_RESTART': '1', 23:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:34 INFO - 'PWD': '/builds/slave/test', 23:16:34 INFO - 'SHELL': '/bin/bash', 23:16:34 INFO - 'SHLVL': '1', 23:16:34 INFO - 'TERM': 'linux', 23:16:34 INFO - 'TMOUT': '86400', 23:16:34 INFO - 'USER': 'cltbld', 23:16:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:34 INFO - '_': '/tools/buildbot/bin/python'} 23:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:16:34 INFO - Cleaning up... 23:16:34 INFO - Return code: 0 23:16:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:16:34 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')]} 23:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:34 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')]} 23:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:16:34 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 0x7f2beafecf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1876350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', '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 23:16:34 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 23:16:34 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 23:16:34 INFO - Using env: {'DISPLAY': ':0', 23:16:34 INFO - 'HOME': '/home/cltbld', 23:16:34 INFO - 'LANG': 'en_US.UTF-8', 23:16:34 INFO - 'LANGUAGE': 'en_US:en', 23:16:34 INFO - 'LOGNAME': 'cltbld', 23:16:34 INFO - 'MAIL': '/var/mail/cltbld', 23:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:34 INFO - 'MOZ_NO_REMOTE': '1', 23:16:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:34 INFO - 'NO_EM_RESTART': '1', 23:16:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:34 INFO - 'PWD': '/builds/slave/test', 23:16:34 INFO - 'SHELL': '/bin/bash', 23:16:34 INFO - 'SHLVL': '1', 23:16:34 INFO - 'TERM': 'linux', 23:16:34 INFO - 'TMOUT': '86400', 23:16:34 INFO - 'USER': 'cltbld', 23:16:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:34 INFO - '_': '/tools/buildbot/bin/python'} 23:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:16:34 INFO - Downloading/unpacking flake8 23:16:34 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 23:16:34 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 23:16:34 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 23:16:34 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 23:16:34 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 23:16:34 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 23:16:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:16:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:16:36 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 23:16:36 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 23:16:36 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 23:16:36 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 23:16:36 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 23:16:36 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 23:16:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:16:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:16:36 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 23:16:36 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 23:16:36 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 23:16:36 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 23:16:36 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 23:16:36 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 23:16:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:16:37 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 23:16:37 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 23:16:37 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 23:16:37 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 23:16:37 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 23:16:37 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 23:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:16:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:16:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:16:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:16:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:16:37 INFO - no previously-included directories found matching 'docs/_build' 23:16:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:16:37 INFO - Running setup.py install for pep8 23:16:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:16:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:16:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:16:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:16:37 INFO - no previously-included directories found matching 'docs/_build' 23:16:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:16:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:16:37 INFO - Cleaning up... 23:16:37 INFO - Return code: 0 23:16:37 INFO - Running post-action listener: _resource_record_post_action 23:16:37 INFO - Running post-action listener: _start_resource_monitoring 23:16:37 INFO - Starting resource monitoring. 23:16:37 INFO - ##### 23:16:37 INFO - ##### Finished create-virtualenv step (success) 23:16:37 INFO - ##### 23:16:37 INFO - ##### 23:16:37 INFO - ##### Running install step. 23:16:37 INFO - ##### 23:16:37 INFO - Running pre-action listener: _resource_record_pre_action 23:16:37 INFO - Running main action method: install 23:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:16:38 INFO - Reading from file tmpfile_stdout 23:16:38 INFO - Detecting whether we're running mozinstall >=1.0... 23:16:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:16:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:16:38 INFO - Reading from file tmpfile_stdout 23:16:38 INFO - Output received: 23:16:38 INFO - Usage: mozinstall [options] installer 23:16:38 INFO - Options: 23:16:38 INFO - -h, --help show this help message and exit 23:16:38 INFO - -d DEST, --destination=DEST 23:16:38 INFO - Directory to install application into. [default: 23:16:38 INFO - "/builds/slave/test"] 23:16:38 INFO - --app=APP Application being installed. [default: firefox] 23:16:38 INFO - mkdir: /builds/slave/test/build/application 23:16:38 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'] 23:16:38 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 23:16:53 INFO - Reading from file tmpfile_stdout 23:16:53 INFO - Output received: 23:16:53 INFO - /builds/slave/test/build/application/firefox/firefox 23:16:53 INFO - Running post-action listener: _resource_record_post_action 23:16:53 INFO - ##### 23:16:53 INFO - ##### Finished install step (success) 23:16:53 INFO - ##### 23:16:53 INFO - ##### 23:16:53 INFO - ##### Running run-tests step. 23:16:53 INFO - ##### 23:16:53 INFO - Running pre-action listener: _resource_record_pre_action 23:16:53 INFO - Running main action method: run_tests 23:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:16:53 INFO - Python 2.7.3 23:16:53 INFO - Return code: 0 23:16:53 INFO - grabbing minidump binary from tooltool 23:16: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')]} 23:16:53 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 0x19051c0>, '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 0x19060c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1906550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:16:53 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 23:16:53 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 23:16:53 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 23:16:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:16:54 INFO - Return code: 0 23:16:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:16:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:16:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:16:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:16:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:16:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:16:54 INFO - Return code: 0 23:16:54 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/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 23:16:54 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/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 23:16:54 INFO - Using env: {'DISPLAY': ':0', 23:16:54 INFO - 'HOME': '/home/cltbld', 23:16:54 INFO - 'LANG': 'en_US.UTF-8', 23:16:54 INFO - 'LANGUAGE': 'en_US:en', 23:16:54 INFO - 'LOGNAME': 'cltbld', 23:16:54 INFO - 'MAIL': '/var/mail/cltbld', 23:16:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:16:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:16:54 INFO - 'MOZ_NO_REMOTE': '1', 23:16:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:16:54 INFO - 'NO_EM_RESTART': '1', 23:16:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:16:54 INFO - 'PWD': '/builds/slave/test', 23:16:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:16:54 INFO - 'SHELL': '/bin/bash', 23:16:54 INFO - 'SHLVL': '1', 23:16:54 INFO - 'TERM': 'linux', 23:16:54 INFO - 'TMOUT': '86400', 23:16:54 INFO - 'USER': 'cltbld', 23:16:54 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174', 23:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:16:54 INFO - '_': '/tools/buildbot/bin/python'} 23:16:54 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/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 23:16:55 INFO - mozversion application_buildid: 20160727203324 23:16:55 INFO - mozversion application_changeset: cff908fa53dc70ded390832f107c3fcd1262155b 23:16:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:16:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:16:55 INFO - mozversion application_name: Firefox 23:16:55 INFO - mozversion application_remotingname: firefox-dev 23:16:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:16:55 INFO - mozversion application_vendor: Mozilla 23:16:55 INFO - mozversion application_version: 49.0a2 23:16:55 INFO - mozversion platform_buildid: 20160727203324 23:16:55 INFO - mozversion platform_changeset: cff908fa53dc70ded390832f107c3fcd1262155b 23:16:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:16:55 INFO - mozversion platform_version: 49.0a2 23:16:55 INFO - using testdate: 1469686615 23:16:55 INFO - actual date: 1469686615 23:16:55 INFO - starting webserver on 'localhost:43155' 23:16:55 INFO - SUITE-START | Running 2 tests 23:16:55 INFO - TEST-START | dromaeo_css 23:16:55 INFO - operating with platform_type : linux_ 23:16:55 INFO - Initialising browser for dromaeo_css test... 23:16:55 INFO - TEST-INFO | started process 30420 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2XvB0E/profile http://localhost:43155/getInfo.html) 23:16:58 INFO - PROCESS | 30420 | __metrics Screen width/height:1600/1200 23:16:58 INFO - PROCESS | 30420 | colorDepth:24 23:16:58 INFO - PROCESS | 30420 | Browser inner width/height: 1024/697 23:16:58 INFO - PROCESS | 30420 | __metrics 23:16:59 INFO - PROCESS | 30420 | 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" 23:16:59 ERROR - PROCESS | 30420 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:17:00 INFO - TEST-INFO | 30420: exit 0 23:17:00 INFO - Browser initialized. 23:17:00 INFO - Running cycle 1/1 for dromaeo_css test... 23:17:00 INFO - TEST-INFO | started process 30511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2XvB0E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:17:12 INFO - PROCESS | 30511 | RSS: Main: 145186816 23:17:12 INFO - PROCESS | 30511 | 23:18:03 INFO - PROCESS | 30511 | 1469686683368 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] 23:20:11 INFO - PROCESS | 30511 | Cycle 1(1): loaded http://localhost:43155/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43155/tests/dromaeo/cssquery-ext.html) 23:20:12 INFO - PROCESS | 30511 | RSS: Main: 247296000 23:20:12 INFO - PROCESS | 30511 | 23:23:14 INFO - PROCESS | 30511 | Cycle 1(1): loaded http://localhost:43155/tests/dromaeo/cssquery-ext.html (next: http://localhost:43155/tests/dromaeo/cssquery-jquery.html) 23:23:15 INFO - PROCESS | 30511 | RSS: Main: 231235584 23:23:15 INFO - PROCESS | 30511 | 23:26:17 INFO - PROCESS | 30511 | Cycle 1(1): loaded http://localhost:43155/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43155/tests/dromaeo/cssquery-mootools.html) 23:26:17 INFO - PROCESS | 30511 | RSS: Main: 220585984 23:26:17 INFO - PROCESS | 30511 | 23:29:19 INFO - PROCESS | 30511 | Cycle 1(1): loaded http://localhost:43155/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43155/tests/dromaeo/cssquery-prototype.html) 23:29:20 INFO - PROCESS | 30511 | RSS: Main: 218652672 23:29:20 INFO - PROCESS | 30511 | 23:32:22 INFO - PROCESS | 30511 | Cycle 1(1): loaded http://localhost:43155/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43155/tests/dromaeo/cssquery-yui.html) 23:32:23 INFO - PROCESS | 30511 | RSS: Main: 200327168 23:32:23 INFO - PROCESS | 30511 | 23:35:25 INFO - PROCESS | 30511 | Cycle 1(1): loaded http://localhost:43155/tests/dromaeo/cssquery-yui.html (next: http://localhost:43155/tests/dromaeo/cssquery-dojo.html) 23:35:26 INFO - PROCESS | 30511 | RSS: Main: 261906432 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - PROCESS | 30511 | __start_tp_report 23:35:26 INFO - PROCESS | 30511 | _x_x_mozilla_page_load 23:35:26 INFO - PROCESS | 30511 | _x_x_mozilla_page_load_details 23:35:26 INFO - PROCESS | 30511 | |i|pagename|runs| 23:35:26 INFO - PROCESS | 30511 | |0;dojo.html;3311.139103833003;3341.8329083545823;3344.6153692325374;3373.3590617782634;3388.6780755828204;5459.153831156167;5548.361938377087;5550.47270509302;5560.9165862512045;5566.972165139168;5444.646098003637;5473.781048758045;5475.67145971242;5475.890482190353;5512.39363669996;4878.097551952882;4910.067087253428;4939.703617782927;4963.230699241605;5012.498750124995;4846.9030619387595;4980.626453016038;4998.150314446535;5009.348784657989;5009.874753131183;4924.655274130812;4944.777485013183;4969.776360063788;4983.900321993558;4993.275974983625;5060.645754797166;5081.441041485434;5081.466446023152;5082.491750824925;5097.108006098918;4809.038192361541;4852.369192005052;4883.389576302962;4989.101961646902;5018.698878067291;5277.102892508283;5287.154055351125;5290.629655924087;5301.814436494743;5348.786048558053;5900.321463031763;5903.380145084747;5904.350521442638;5905.350411454737;5920.881582368372;5801.88396232073;5867.295924489046;5878.177055212273;5935.287765468128;5950.345461999176;5504.2294078829;5555.083411237141;5556.499915007628;5558.277423934944;5561.165825126263;5786;6067;6071.241094863142;6074.3925607438905;6075.453209211192;5736.225609542742;5843.941560584425;5860.531157507475;5889.1755154278435;5894.911576326359;5926.911096333559;5930.140129681258;5938.109283607493;5964.821055368346;5971.671558064261;5587.580931430159;5700.315962084576;5895.970520147372;5912.201852749824;5918.704064796743;5734.340550836624;5811.418858114154;5814.622049566765;5817.360090390053;5855.970720146372;4470.374147619336;4481.5070342262325;4487.125010622897;4487.371767952489;4520.32195170727;3933.0757272041046;3993.980030099831;4012.057166565855;4036.0515278909434;4044.312466880637;5579.023670857584;5598.328200615871;5604.327480702342;5609.411011843779;5627.887442251132;5871.236739223874;5889.558283128783;5893.322267939242;5900.911486327708;5926.496247818897;5471.781128754806;5542.11326187808;5583.078791999355;5599.552035837123;5609.775608975677;4990.8253211137435;5025.120603894377;5025.296458495814;5027.748612569357;5029.17018691919;6142.723577439026;6145.723442445101;6151.015837465984;6159.876802463926;6160.907586386208;5677.914831277617;5815.069588865761;5858.5898987070495;5874.647521148745;5913.881722365442;6110.7555697771595;6120.663363514871;6125.264968203844;6144.723487442986;6147.108669242845;5759.798407055733;5767.048437007847;5767.884642307215;5768.480836724715;5777.133429985536;5664.178694089416;5780.046292361713;5834.299884013945;5840.474357307863;5884.941150588611;5710.857228569319;5722.313322401338;5735.942640573541;5736.71316434185;5751.511121554715;5673.687947162781;5716.114002329646;5731.828045158652;5732.426757324234;5735.397783232867;5524.696141712245;5594.888102238014;5620.381758006698;5622.943770562242;5685.77256909719;5769.798057067983;5781.508571771446;5789.8263052108505;5791.7683292667825;5803.680797556175;5450;5634.211210430461;5636.957162924872;5645.294338207724;5657.038303488499 23:35:26 INFO - PROCESS | 30511 | |1;ext.html;603.3604379357881;643.871225754849;652.1228947066197;657.6514447342909;665.0689035350512;33186.33627327452;36005.09987250317;36045;46864.29703554441;47781.28328075073;48888.2666759998;49254.50745492544;49355.25967110496;49389.25916111261;49528;8180.263776260135;8948.73153805387;9114.088591140866;9117.316201284897;9120.589573469177;8264;8552.486850788942;8570.27152692022;10596.364218146897;10804.945975270151;10994.230403871732;11070.059044981146;11075.280106793072;11079.667609971713;11083.556657733681;8226.42415030948;10922.508487118099;10972.725681857937;10996.780064398667;11005.119590432787;10579.523921423477;10908.945455272673;10920.781584368267;10927.562897484091;11311.547538098466;41302.79348603238;42107;45242.77378613119;45349.54650453486;45400.3189952151;3836.9616303836883;3849.1147036181833;3854.5759966403675;3875.7286989910717;4051.5948405159543;34377.48433773495;35441.11397215065;35481;35505.28989420223;35564.82217588921;4682.695624784378;4775.982947177011;4782.168789429852;4820.68665536739;4927.334809800667;362995;364677;364687;366635;368679;69430.65284673544;70031.64984175046;70045;70072;70094.64952675204;40853.38719919204;41678.58321416807;41736.37395439071;43779.562204378184;74392.25607743853;14352.49766258176;14371.13773173592;14440.494582689555;14441.711165776836;14464;9582.568784404719;10161.136303414145;10162.745931351776;10163.69508914719;10258.333208341433;21410.250641227794;22753.203637872924;22794.20220292315;22799.544009119723;22937;8147.737100749393;8349.499030058216;8380.16198380157;8525.829483410296;8587.613557389932;1033.178622994721;1104.3153244987998;1116.268843907242;1193.540486912545;1202.4348556178581;34615.653843461245;35063.82468087643;35099.473507897404;35126.29747405038;35313.470297945554;1184.5972369394447;1189.1259923955886;1198.4966314148078;1213.059878594079;1250.9812352814715;17567.912160439115;18113.54716132081;18324.633507329778;18326.725099123523;18439.90780046091;627980;632450;634472;636184;642243;64717.67641161764;66062;66114.33885661178;66214.33785662081;66247.33752662412;1298.4741179822188;1403.3544569498144;1419.06341814402;1436.5977526292654;1468.941242350294;34530;35727.46408803818;35790.64209357873;35886;35994;54787.72606136944;55605.721971391504;55638;55662.721686391305;55687;430597;435786;437284;438270;438308;27393.178204653894;28011.71988280091;28083.43833123367;28376.716232837407;29839;51779.482205178974;52459;53131.4686853142;53943;54168;43823.56176438322;43863.78068109639;44000;44545.33182002338;44985;43386;43977;44381;44401.11197776091;45612.315815263464;44589;44705.10589788251;45007.54992450034;45134.77432612816;45313.320300194864;37826.810865945496;39881.40177897276;39967.60032399639;40628;40997.795011024755;29717.554236686035;29897.09119029197;29961;30264.243393915327;30559.388812223187 23:35:26 INFO - PROCESS | 30511 | |2;jquery.html;382.42445120094254;382.8334674416629;383.0864830173285;398.631266078877;404.56509252553496;18333.816661833378;19279.51801204967;20637.587248255026;20707.171713131476;20738.792612073874;11080.113590912728;11551.480183391746;11567.768644627102;11576.53693852247;15737.291821868015;5173.793048278064;5363.892722145555;5391.245225668409;5393.487618676239;5397.892042159155;3948.3287841066945;5572.749226284827;5862.325832529271;5867.794627188049;5956.553258505608;5867.11993201019;5919.319278283009;5928.40715928408;5935.940640593603;5951.970240148793;5573.5819813514145;5686.2039314496005;5902.852428689274;5904.616199947011;5913.615614985012;3824.2733880562605;5447.482489163563;5765.308163020464;5769.538436925036;5879.029960056586;13766.587002389944;13793.862061379357;17247.65504689899;24269.635955460682;24296.271111866674;2557.0794513974956;2566.089038391363;2623.3048242215827;2822.280318518785;2848.3591191981845;9909.75225619358;10170.491475426199;14604.196769177692;14653.92673036628;14777.408903643842;2010.497375656086;2015.0629957070032;2040.2655044184082;2075.7509098908076;2087.4468265909545;88099;89815.55092224627;90231.54884225603;92026;93645;10774.245802793886;10948.562057517769;11078.67347856943;11169.664910052712;11284.943575282236;15314.310856011507;15382.923085384502;15437.536873893801;15449.768253476206;15474.22628868552;7057.717691292393;7359.337659610661;7432.368248698921;7497.962510187414;7507.099148102154;6721.831954201184;6850.794476165723;6897.827554311182;6924.23833378328;6927.064846245793;4747.359106520645;4796.544328288807;4810.39870016248;4879.072976134523;4920.606351491872;1750.5740951167222;1763.2153691607368;1830.283689000907;1958.8237058664788;1970.763508378975;983.3559018842669;1075.9139268858473;1080.5947769586405;1088.4666513408372;1161.3438407299848;18787.24851005972;19422.240361547592;19621.117049732795;19713.112909919088;19772.20911163566;1038.1072277841051;1043.6503771236721;1055.9788804223872;1076.1283360478037;1096.6764804382726;10761.408122553335;11037.889621103846;11075.944620276847;11139.387333696563;11158.721031974266;508446;528009;535377;551791;556217;97361;97368.51315743518;98238.50880745694;98367;98507;1030.3199888073984;1049.7008352619466;1055.8785739639886;1073.1361254190297;1077.6605369308643;16666.50000499987;17295.481135565955;17298.221580028676;17468.82531174672;18111.184996675445;38415.807920961335;40931.1813763729;42057.78971105125;42218.36672449977;42749.145017100105;58741;60464.09303860534;61047.38952610417;61591.38408615856;62593.68703156455;7269.927300726925;7765.262300081483;7966.960165199137;8820.2061814437;8846.336524760798;8948.73153805387;12882.871171288167;13019.544315948897;13348;13657.658558536115;11783.175177737476;11914.285142891455;12202.511899523919;12220.572279970158;12235;11683.707907302385;11734.94132529332;11790.705232368917;11870.762584748429;12260.14179007461;12038.337891415706;12050.216735912138;12101.031917446759;12116.818247726467;12260.38698065111;8470.32237421017;8638.17937295956;8702.173293537104;8712.346574006848;8818;11412.144089193178;11986.101042421678;12141.757164856477;12186.817197742219;12419.130660854014 23:35:26 INFO - PROCESS | 30511 | |3;mootools.html;1452.8038714773502;1550.775137605047;1586.6747316800056;1764.8145098097937;1917.453525745162;9131.817363652732;9507.096825801538;9536.952315238423;9827.164691001255;9856.063673950981;9581.233501319895;9752.609895604184;9763.804723905536;9768.218542516599;9778.413295202294;2094.2984100326407;2150.483883867876;2154.062982602565;2419.262125051854;2450.375154335642;2394.521095780842;2470.654108424822;2471.9505609887883;2489.65144879717;2493.6758221431187;2383.0467812874817;2395.9880200598973;2397.077125306752;2401.2916189724074;2423.3941514621347;1997.5106099005836;2064.721262629541;2432.027189124347;2436.013414567103;2438.4879175373103;2090.247510896076;2321.0831721469926;2372.4899146683374;2374.7625237476113;2392.9042838286446;9549.188318992825;9593.568289426923;9605.13553780163;9607.90392096077;9632.47021413822;5343.385510666284;5413.566914646819;5474.644148130398;5488.615796894219;5489.643173193769;3174.555562221291;3213.8393080346123;3263.8694452221885;3473.0796338970194;3491.109767009422;1043.2801367056707;1087.0922779479145;1101.8126918423807;1119.3507765495992;1123.7696272264166;13635.113717608225;13692.383842727102;13698.109622874481;13701.931490342484;13726.862731372657;4526.95473045272;4610.838620648328;4686.437627484724;4753.952460475351;4769.546893045155;11342.035926946137;11346.262492937933;11356.148288878367;11376.260543064674;11389.772204555862;5252.9737351313;5265.21021846726;5268.394134674561;5275.4460781618145;5297.735113244323;4304.16068866568;4630.629549636021;4684.672072954929;4713.8350157745;4919.458859525521;4143.81352839123;4164.604362585549;4191.392248124005;4197.223513649985;4235.597618226264;1221.157401393036;1224.9326287054298;1234.7221875078035;1243.5771837575269;1245.8691837356946;912.5391677203103;951.7192428233687;976.321456587679;981.097390400833;994.3834822410151;6345.777897773556;6466.579672321378;6468.15913931186;6509.251436084816;6600.702968366435;451.68607817566476;461.5107985535343;467.5721714631142;475.3748820301293;478.58124141376294;4354.129174165179;4381.364702118175;4428.955710442919;4506.526814684476;4539.659525535598;11787.469563869647;12221.144519883523;12221.450034748457;12223.755524889453;12232.963122169662;11435.942820285845;11912.404379780975;11912.702182445508;12153.088518361159;12224.1443098984;8887.15572020657;8951;9006.234470070118;9009.954950225338;9017.909820901707;6360.93639063622;6729.55159588779;6735.225449073419;6765.695543700447;6865.519413640997;10774.946125269636;10946.233763636788;11240.9321114494;11244.212905096892;11339.659810205707;11263.436828158723;11364.659060228207;11407.315561066363;11502.654920352403;11654.475548600503;7879.960600196962;8327.958360208402;8451.366147538836;8457.915420845713;8613.3109351253;9566.378185417925;9605.18355939753;9611.903880961101;9699.757506062122;9721.173700235558;8436.32509399259;8635.827283454422;8653.956730216309;8676.175763302475;8799.47203167812;8158.184181581794;8242.670293188445;8308.543030133158;8388.958055209685;8414.242718155396;7796.883046754417;7896.802579935547;7932.960335198287;7935.523868567904;7936.682532698858;5479.6164268500825;5575.163725441054;5688;5736.42635736423;5779.364269930388;5407.675539467645;5461.34463864323;5474.616776825584;5489.258950041693;5578.442155784389 23:35:26 INFO - PROCESS | 30511 | |4;prototype.html;300.93981203759245;305.73859350255543;310.6924145096354;324.3529159327141;325.2632786738037;5271.42014378418;5377.919331210025;5435.619506634537;5457.399686034538;5493.917591236134;5374.3013408256875;5405.108157154074;5410.08028635132;5424.349078110632;5440.972795136028;4240.787960601973;4249.3625956106525;4251;4259.5740425957465;4294.699371044028;3969.503812023497;4075.694322925792;4169.687273454488;4249.383839343293;4260.957390426102;4134.676903390916;4152.335626299792;4173.165366926612;4177.120295866857;4189.392538081976;4143.295639741236;4160.791960401998;4170.77060761658;4189.07840275139;4222.641075508585;3591.712662986955;3634.4889805387356;3683.5027271318295;3685.594584595692;3693.519842420468;5153.742312884341;5160.200168973815;5184.11869982104;5184.533391994739;5186.377634683862;4704.788284527204;4745.501722319142;4747.667663263573;4785.846369133904;4790.353302304214;5447.101228297326;5567.691592475765;5568.3596386415675;5598.468145526169;5613.747381367807;3976.0517346500296;3987.960120398817;4028.8388464461386;4029.9245888367514;4040.7777572233517;5741.684207368593;5760.855978600526;5828.912566311508;5856.76572937082;5860.04067945689;3999.680025597945;4065.10567675111;4091;4101.507819061731;4103.8974025649595;4132.752034877917;4152.833886644561;4162.459889840718;4163.562825903297;4165.354370072643;4742.5020372861045;4744.5492678195515;4751.83368582098;4781.521847815191;4781.545753153515;5439;5647.463490968352;5658.97170514153;5669.6314739541795;5704.286964129484;1395;1408.4929425406845;1412.576227131877;1426.978595321071;1433.9426422943175;895.4895709445553;929.934904556688;931.189864817613;942.641796117471;946.6828612414918;4877.244027175793;4892.68197567157;4901.264810278415;4955.776990035457;5020.698758074527;5112.089251722436;5145.559243411851;5148.760289274178;5209.244659524347;5239.821040265986;4707.693999890064;4917.311576379309;4930.630202734809;4949.90100197994;4998.075356059141;2745.492083964473;2753.655793025872;2786.4845003674386;2803.9158825235277;2804.765903002672;5302.575793936476;5351.946480535223;5384.838454846283;5403.594730395236;5410.648307860055;5051.898962020812;5227.398849132398;5274.789008439696;5279.465856388955;5301.708406037628;5191.688498690015;5382.946170538323;5390.568754499709;5396.730163491889;5404.972975135099;5099.745012749274;5102.183650615883;5120.513551212629;5155.922661160011;5157.535821776059;4842.322074909586;5076.36545431821;5094.2103973884105;5099.628672667228;5099.79600815978;5297.682139071668;5299.761510731949;5306.389765177054;5317.867053323698;5357.571394288369;3852.7880966545986;3959.9010024749614;4057.046594050337;4112.91774164521;4129.8967525812095;5738.68437235956;5755.913661295168;5756.186801157622;5768.394318596487;5779.266220134027;5414.35027796667;5540.198788448977;5576.470235327637;5600.57995650336;5632.802851900164;5532.446755324597;5573.7770489179975;5583.664980101207;5590.888182236251;5594.62931581765;5472.455308281668;5492.560595152298;5495.175723641486;5503.752331145028;5504.284443022541;5383.757730902041;5581.330240371181;5600.327960644749;5687.402822703557;5741;5548.389677135431;5582.720863956867;5590.133529302964;5596.832095037155;5597.916031259453 23:35:26 INFO - PROCESS | 30511 | |5;yui.html;726.8437285983513;743.6430513353594;821.8849361089444;885.8228354329134;899.6851102114259;13288.800667989975;13868.167909925387;13917.443302267922;13932.094413863104;13935.860641393583;12634.81047784284;12659.55691550796;12949.935250323759;12951.352432378391;12960.287184204864;707.405779145519;851.0723311590365;875.7022612311813;877.3419935048713;877.5743764274322;870.8084221471266;874.7681864305964;876.8947726272856;877.324460165672;877.8332116897801;753.6646192444365;918.4076270805326;918.4168053286145;921.0283151275402;921.5714692667941;763.3969164360149;767.5548182054395;886.410536992899;908.8318661047728;910.5856835139996;668.8762578922915;668.9163854518185;671.9126513553256;673.9764108256187;675.1965161457849;13179.60461186166;13212.801807972792;13221.140625859289;13230.338483075808;13231.073824832265;14768;15011.624709382244;15048.473303434328;15180.620484487865;15312.693746125127;13256.40346184424;13400.865991340157;13744.862551374557;13779.862201377959;13900.374483148282;4730.385049943486;4746.572808447257;4786.473487916326;4799.952000479985;4904.877378065541;74274;74492.25507744908;74593.6270318645;75126.24873751248;79850.60074699647;12270.631881043404;12318.938405307916;12408;12593.622191334274;12599.748005040032;23058.538829223322;23164.305070847564;23235.53528929412;23238.65142022871;23246.767532324797;8871.955640221757;9020.278377729766;9047.592858321392;9190.816183676288;9325.673601424054;4783.521647835189;4867.0995865764235;4871.48849370811;5015.4733752956145;5064.5948324134715;3713.814309284525;3822.4266360046217;4092.9386059209137;4270.274053410928;4288.09949910516;736.8415790604938;753.179034852003;769.5844244108231;769.7113582406597;774.4694884004475;685.9588424694534;688.0745397440435;688.9827754306183;719.4891626944927;780.6448066129898;9923.255755818342;10558.260921735558;10635.787284254271;10649.9467502662;10680.412577308323;749.4528993834531;775.7401270574308;783.7531177679012;796.3617587480624;798.9001374828147;5104.897902041938;5105.185715421953;5159.509846564571;5205.046537316544;5211.270422140903;85153;127764.36117819352;128440;130221.34889325683;132579;69428.6528567354;70585.29414705787;72771.63614181895;111591;112269.43865280785;98837;98867;99237.50381248193;100033.49983250037;100734.49632751789;9631.325807193429;9652.27607929422;9678.870648457329;9878.753031174278;9890.159336456481;41226.175476490906;55193.44806551883;55556;55747.721261395054;57214.42785572091;81787.5910620443;81870.59064704638;82336;83449.58275208584;85741.57129214314;12469.750604987668;12555.811662825248;12864.485420583072;12903.354832258537;13525.526606568723;15494.845051549792;15553.455629052929;15683.215839207765;16255;16351.67296654084;14738.63153421173;14845.076612661358;15162.3176957035;15724.449644262397;15726.370945162524;14388.28058597087;14417.42330306776;14710.705785884436;14758.63103422423;15688.137152456724;13227.206367617973;13474.86525134736;13695.315234238049;13930.860691392956;14352.210628415538;10928.50821713009;10967.670969870916;11019.944900275448;11145.777084458103;11357.716057098638;11085.556577736801;11257.383047088031;11449.313041217554;11462.94268528652;11537.711557211136 23:35:26 INFO - PROCESS | 30511 | __end_tp_report 23:35:26 INFO - PROCESS | 30511 | __start_cc_report 23:35:26 INFO - PROCESS | 30511 | _x_x_mozilla_cycle_collect,886 23:35:26 INFO - PROCESS | 30511 | __end_cc_report 23:35:26 INFO - PROCESS | 30511 | __startTimestamp1469687726102__endTimestamp 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - PROCESS | 30511 | ------- Summary: start ------- 23:35:26 INFO - PROCESS | 30511 | Number of tests: 6 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - PROCESS | 30511 | [#0] dojo.html Cycles:165 Average:5454.28 Median:5636.96 stddev:607.96 (10.8%) stddev-sans-first:586.12 23:35:26 INFO - PROCESS | 30511 | Values: 3311.1 3341.8 3344.6 3373.4 3388.7 5459.2 5548.4 5550.5 5560.9 5567.0 5444.6 5473.8 5475.7 5475.9 5512.4 4878.1 4910.1 4939.7 4963.2 5012.5 4846.9 4980.6 4998.2 5009.3 5009.9 4924.7 4944.8 4969.8 4983.9 4993.3 5060.6 5081.4 5081.5 5082.5 5097.1 4809.0 4852.4 4883.4 4989.1 5018.7 5277.1 5287.2 5290.6 5301.8 5348.8 5900.3 5903.4 5904.4 5905.4 5920.9 5801.9 5867.3 5878.2 5935.3 5950.3 5504.2 5555.1 5556.5 5558.3 5561.2 5786.0 6067.0 6071.2 6074.4 6075.5 5736.2 5843.9 5860.5 5889.2 5894.9 5926.9 5930.1 5938.1 5964.8 5971.7 5587.6 5700.3 5896.0 5912.2 5918.7 5734.3 5811.4 5814.6 5817.4 5856.0 4470.4 4481.5 4487.1 4487.4 4520.3 3933.1 3994.0 4012.1 4036.1 4044.3 5579.0 5598.3 5604.3 5609.4 5627.9 5871.2 5889.6 5893.3 5900.9 5926.5 5471.8 5542.1 5583.1 5599.6 5609.8 4990.8 5025.1 5025.3 5027.7 5029.2 6142.7 6145.7 6151.0 6159.9 6160.9 5677.9 5815.1 5858.6 5874.6 5913.9 6110.8 6120.7 6125.3 6144.7 6147.1 5759.8 5767.0 5767.9 5768.5 5777.1 5664.2 5780.0 5834.3 5840.5 5884.9 5710.9 5722.3 5735.9 5736.7 5751.5 5673.7 5716.1 5731.8 5732.4 5735.4 5524.7 5594.9 5620.4 5622.9 5685.8 5769.8 5781.5 5789.8 5791.8 5803.7 5450.0 5634.2 5637.0 5645.3 5657.0 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - PROCESS | 30511 | [#1] ext.html Cycles:180 Average:64925.09 Median:22339.80 stddev:130880.62 (585.9%) stddev-sans-first:131158.68 23:35:26 INFO - PROCESS | 30511 | Values: 603.4 643.9 652.1 657.7 665.1 33186.3 36005.1 36045.0 46864.3 47781.3 48888.3 49254.5 49355.3 49389.3 49528.0 8180.3 8948.7 9114.1 9117.3 9120.6 8264.0 8552.5 8570.3 10596.4 10804.9 10994.2 11070.1 11075.3 11079.7 11083.6 8226.4 10922.5 10972.7 10996.8 11005.1 10579.5 10908.9 10920.8 10927.6 11311.5 41302.8 42107.0 45242.8 45349.5 45400.3 3837.0 3849.1 3854.6 3875.7 4051.6 34377.5 35441.1 35481.0 35505.3 35564.8 4682.7 4776.0 4782.2 4820.7 4927.3 362995.0 364677.0 364687.0 366635.0 368679.0 69430.7 70031.6 70045.0 70072.0 70094.6 40853.4 41678.6 41736.4 43779.6 74392.3 14352.5 14371.1 14440.5 14441.7 14464.0 9582.6 10161.1 10162.7 10163.7 10258.3 21410.3 22753.2 22794.2 22799.5 22937.0 8147.7 8349.5 8380.2 8525.8 8587.6 1033.2 1104.3 1116.3 1193.5 1202.4 34615.7 35063.8 35099.5 35126.3 35313.5 1184.6 1189.1 1198.5 1213.1 1251.0 17567.9 18113.5 18324.6 18326.7 18439.9 627980.0 632450.0 634472.0 636184.0 642243.0 64717.7 66062.0 66114.3 66214.3 66247.3 1298.5 1403.4 1419.1 1436.6 1468.9 34530.0 35727.5 35790.6 35886.0 35994.0 54787.7 55605.7 55638.0 55662.7 55687.0 430597.0 435786.0 437284.0 438270.0 438308.0 27393.2 28011.7 28083.4 28376.7 29839.0 51779.5 52459.0 53131.5 53943.0 54168.0 43823.6 43863.8 44000.0 44545.3 44985.0 43386.0 43977.0 44381.0 44401.1 45612.3 44589.0 44705.1 45007.5 45134.8 45313.3 37826.8 39881.4 39967.6 40628.0 40997.8 29717.6 29897.1 29961.0 30264.2 30559.4 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - PROCESS | 30511 | [#2] jquery.html Cycles:180 Average:30535.08 Median:24282.95 stddev:88544.76 (364.6%) stddev-sans-first:88764.21 23:35:26 INFO - PROCESS | 30511 | Values: 382.4 382.8 383.1 398.6 404.6 18333.8 19279.5 20637.6 20707.2 20738.8 11080.1 11551.5 11567.8 11576.5 15737.3 5173.8 5363.9 5391.2 5393.5 5397.9 3948.3 5572.7 5862.3 5867.8 5956.6 5867.1 5919.3 5928.4 5935.9 5952.0 5573.6 5686.2 5902.9 5904.6 5913.6 3824.3 5447.5 5765.3 5769.5 5879.0 13766.6 13793.9 17247.7 24269.6 24296.3 2557.1 2566.1 2623.3 2822.3 2848.4 9909.8 10170.5 14604.2 14653.9 14777.4 2010.5 2015.1 2040.3 2075.8 2087.4 88099.0 89815.6 90231.5 92026.0 93645.0 10774.2 10948.6 11078.7 11169.7 11284.9 15314.3 15382.9 15437.5 15449.8 15474.2 7057.7 7359.3 7432.4 7498.0 7507.1 6721.8 6850.8 6897.8 6924.2 6927.1 4747.4 4796.5 4810.4 4879.1 4920.6 1750.6 1763.2 1830.3 1958.8 1970.8 983.4 1075.9 1080.6 1088.5 1161.3 18787.2 19422.2 19621.1 19713.1 19772.2 1038.1 1043.7 1056.0 1076.1 1096.7 10761.4 11037.9 11075.9 11139.4 11158.7 508446.0 528009.0 535377.0 551791.0 556217.0 97361.0 97368.5 98238.5 98367.0 98507.0 1030.3 1049.7 1055.9 1073.1 1077.7 16666.5 17295.5 17298.2 17468.8 18111.2 38415.8 40931.2 42057.8 42218.4 42749.1 58741.0 60464.1 61047.4 61591.4 62593.7 7269.9 7765.3 7967.0 8820.2 8846.3 8948.7 12882.9 13019.5 13348.0 13657.7 11783.2 11914.3 12202.5 12220.6 12235.0 11683.7 11734.9 11790.7 11870.8 12260.1 12038.3 12050.2 12101.0 12116.8 12260.4 8470.3 8638.2 8702.2 8712.3 8818.0 11412.1 11986.1 12141.8 12186.8 12419.1 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - PROCESS | 30511 | [#3] mootools.html Cycles:180 Average:6271.48 Median:2549.10 stddev:3753.75 (147.3%) stddev-sans-first:3746.82 23:35:26 INFO - PROCESS | 30511 | Values: 1452.8 1550.8 1586.7 1764.8 1917.5 9131.8 9507.1 9537.0 9827.2 9856.1 9581.2 9752.6 9763.8 9768.2 9778.4 2094.3 2150.5 2154.1 2419.3 2450.4 2394.5 2470.7 2472.0 2489.7 2493.7 2383.0 2396.0 2397.1 2401.3 2423.4 1997.5 2064.7 2432.0 2436.0 2438.5 2090.2 2321.1 2372.5 2374.8 2392.9 9549.2 9593.6 9605.1 9607.9 9632.5 5343.4 5413.6 5474.6 5488.6 5489.6 3174.6 3213.8 3263.9 3473.1 3491.1 1043.3 1087.1 1101.8 1119.4 1123.8 13635.1 13692.4 13698.1 13701.9 13726.9 4527.0 4610.8 4686.4 4754.0 4769.5 11342.0 11346.3 11356.1 11376.3 11389.8 5253.0 5265.2 5268.4 5275.4 5297.7 4304.2 4630.6 4684.7 4713.8 4919.5 4143.8 4164.6 4191.4 4197.2 4235.6 1221.2 1224.9 1234.7 1243.6 1245.9 912.5 951.7 976.3 981.1 994.4 6345.8 6466.6 6468.2 6509.3 6600.7 451.7 461.5 467.6 475.4 478.6 4354.1 4381.4 4429.0 4506.5 4539.7 11787.5 12221.1 12221.5 12223.8 12233.0 11435.9 11912.4 11912.7 12153.1 12224.1 8887.2 8951.0 9006.2 9010.0 9017.9 6360.9 6729.6 6735.2 6765.7 6865.5 10774.9 10946.2 11240.9 11244.2 11339.7 11263.4 11364.7 11407.3 11502.7 11654.5 7880.0 8328.0 8451.4 8457.9 8613.3 9566.4 9605.2 9611.9 9699.8 9721.2 8436.3 8635.8 8654.0 8676.2 8799.5 8158.2 8242.7 8308.5 8389.0 8414.2 7796.9 7896.8 7933.0 7935.5 7936.7 5479.6 5575.2 5688.0 5736.4 5779.4 5407.7 5461.3 5474.6 5489.3 5578.4 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - PROCESS | 30511 | [#4] prototype.html Cycles:180 Average:4572.37 Median:5147.16 stddev:1318.99 (25.6%) stddev-sans-first:1283.14 23:35:26 INFO - PROCESS | 30511 | Values: 300.9 305.7 310.7 324.4 325.3 5271.4 5377.9 5435.6 5457.4 5493.9 5374.3 5405.1 5410.1 5424.3 5441.0 4240.8 4249.4 4251.0 4259.6 4294.7 3969.5 4075.7 4169.7 4249.4 4261.0 4134.7 4152.3 4173.2 4177.1 4189.4 4143.3 4160.8 4170.8 4189.1 4222.6 3591.7 3634.5 3683.5 3685.6 3693.5 5153.7 5160.2 5184.1 5184.5 5186.4 4704.8 4745.5 4747.7 4785.8 4790.4 5447.1 5567.7 5568.4 5598.5 5613.7 3976.1 3988.0 4028.8 4029.9 4040.8 5741.7 5760.9 5828.9 5856.8 5860.0 3999.7 4065.1 4091.0 4101.5 4103.9 4132.8 4152.8 4162.5 4163.6 4165.4 4742.5 4744.5 4751.8 4781.5 4781.5 5439.0 5647.5 5659.0 5669.6 5704.3 1395.0 1408.5 1412.6 1427.0 1433.9 895.5 929.9 931.2 942.6 946.7 4877.2 4892.7 4901.3 4955.8 5020.7 5112.1 5145.6 5148.8 5209.2 5239.8 4707.7 4917.3 4930.6 4949.9 4998.1 2745.5 2753.7 2786.5 2803.9 2804.8 5302.6 5351.9 5384.8 5403.6 5410.6 5051.9 5227.4 5274.8 5279.5 5301.7 5191.7 5382.9 5390.6 5396.7 5405.0 5099.7 5102.2 5120.5 5155.9 5157.5 4842.3 5076.4 5094.2 5099.6 5099.8 5297.7 5299.8 5306.4 5317.9 5357.6 3852.8 3959.9 4057.0 4112.9 4129.9 5738.7 5755.9 5756.2 5768.4 5779.3 5414.4 5540.2 5576.5 5600.6 5632.8 5532.4 5573.8 5583.7 5590.9 5594.6 5472.5 5492.6 5495.2 5503.8 5504.3 5383.8 5581.3 5600.3 5687.4 5741.0 5548.4 5582.7 5590.1 5596.8 5597.9 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - PROCESS | 30511 | [#5] yui.html Cycles:180 Average:21497.33 Median:4785.00 stddev:31277.52 (653.7%) stddev-sans-first:31326.38 23:35:26 INFO - PROCESS | 30511 | Values: 726.8 743.6 821.9 885.8 899.7 13288.8 13868.2 13917.4 13932.1 13935.9 12634.8 12659.6 12949.9 12951.4 12960.3 707.4 851.1 875.7 877.3 877.6 870.8 874.8 876.9 877.3 877.8 753.7 918.4 918.4 921.0 921.6 763.4 767.6 886.4 908.8 910.6 668.9 668.9 671.9 674.0 675.2 13179.6 13212.8 13221.1 13230.3 13231.1 14768.0 15011.6 15048.5 15180.6 15312.7 13256.4 13400.9 13744.9 13779.9 13900.4 4730.4 4746.6 4786.5 4800.0 4904.9 74274.0 74492.3 74593.6 75126.2 79850.6 12270.6 12318.9 12408.0 12593.6 12599.7 23058.5 23164.3 23235.5 23238.7 23246.8 8872.0 9020.3 9047.6 9190.8 9325.7 4783.5 4867.1 4871.5 5015.5 5064.6 3713.8 3822.4 4092.9 4270.3 4288.1 736.8 753.2 769.6 769.7 774.5 686.0 688.1 689.0 719.5 780.6 9923.3 10558.3 10635.8 10649.9 10680.4 749.5 775.7 783.8 796.4 798.9 5104.9 5105.2 5159.5 5205.0 5211.3 85153.0 127764.4 128440.0 130221.3 132579.0 69428.7 70585.3 72771.6 111591.0 112269.4 98837.0 98867.0 99237.5 100033.5 100734.5 9631.3 9652.3 9678.9 9878.8 9890.2 41226.2 55193.4 55556.0 55747.7 57214.4 81787.6 81870.6 82336.0 83449.6 85741.6 12469.8 12555.8 12864.5 12903.4 13525.5 15494.8 15553.5 15683.2 16255.0 16351.7 14738.6 14845.1 15162.3 15724.4 15726.4 14388.3 14417.4 14710.7 14758.6 15688.1 13227.2 13474.9 13695.3 13930.9 14352.2 10928.5 10967.7 11019.9 11145.8 11357.7 11085.6 11257.4 11449.3 11462.9 11537.7 23:35:26 INFO - PROCESS | 30511 | -------- Summary: end -------- 23:35:26 INFO - PROCESS | 30511 | 23:35:26 INFO - TEST-INFO | 30511: exit 0 23:35:26 INFO - TEST-OK | dromaeo_css | took 1111670ms 23:35:26 INFO - TEST-START | kraken 23:35:26 INFO - operating with platform_type : linux_ 23:35:26 INFO - Initialising browser for kraken test... 23:35:26 INFO - TEST-INFO | started process 30704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgvvBnv/profile http://localhost:43155/getInfo.html) 23:35:30 INFO - PROCESS | 30704 | __metrics Screen width/height:1600/1200 23:35:30 INFO - PROCESS | 30704 | colorDepth:24 23:35:30 INFO - PROCESS | 30704 | Browser inner width/height: 1024/697 23:35:30 INFO - PROCESS | 30704 | __metrics 23:35:31 INFO - PROCESS | 30704 | 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" 23:35:31 ERROR - PROCESS | 30704 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:35:31 INFO - TEST-INFO | 30704: exit 0 23:35:31 INFO - Browser initialized. 23:35:31 INFO - Running cycle 1/1 for kraken test... 23:35:31 INFO - TEST-INFO | started process 30797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgvvBnv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:35:43 INFO - PROCESS | 30797 | RSS: Main: 146014208 23:35:43 INFO - PROCESS | 30797 | 23:35:46 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43155/tests/kraken/driver.html?testName=audio-beat-detection) 23:35:47 INFO - PROCESS | 30797 | RSS: Main: 194859008 23:35:47 INFO - PROCESS | 30797 | 23:35:49 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43155/tests/kraken/driver.html?testName=audio-dft) 23:35:50 INFO - PROCESS | 30797 | RSS: Main: 187592704 23:35:50 INFO - PROCESS | 30797 | 23:35:53 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43155/tests/kraken/driver.html?testName=audio-fft) 23:35:53 INFO - PROCESS | 30797 | RSS: Main: 279228416 23:35:53 INFO - PROCESS | 30797 | 23:35:55 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43155/tests/kraken/driver.html?testName=audio-oscillator) 23:35:56 INFO - PROCESS | 30797 | RSS: Main: 188010496 23:35:56 INFO - PROCESS | 30797 | 23:35:58 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43155/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:35:59 INFO - PROCESS | 30797 | RSS: Main: 189153280 23:35:59 INFO - PROCESS | 30797 | 23:36:02 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43155/tests/kraken/driver.html?testName=imaging-darkroom) 23:36:02 INFO - PROCESS | 30797 | RSS: Main: 224043008 23:36:02 INFO - PROCESS | 30797 | 23:36:06 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43155/tests/kraken/driver.html?testName=imaging-desaturate) 23:36:06 INFO - PROCESS | 30797 | RSS: Main: 222556160 23:36:06 INFO - PROCESS | 30797 | 23:36:09 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43155/tests/kraken/driver.html?testName=json-parse-financial) 23:36:10 INFO - PROCESS | 30797 | RSS: Main: 222715904 23:36:10 INFO - PROCESS | 30797 | 23:36:11 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43155/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:36:12 INFO - PROCESS | 30797 | RSS: Main: 201191424 23:36:12 INFO - PROCESS | 30797 | 23:36:13 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43155/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:36:14 INFO - PROCESS | 30797 | RSS: Main: 203177984 23:36:14 INFO - PROCESS | 30797 | 23:36:16 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43155/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:36:17 INFO - PROCESS | 30797 | RSS: Main: 210145280 23:36:17 INFO - PROCESS | 30797 | 23:36:19 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43155/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:36:20 INFO - PROCESS | 30797 | RSS: Main: 214487040 23:36:20 INFO - PROCESS | 30797 | 23:36:23 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43155/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:36:23 INFO - PROCESS | 30797 | RSS: Main: 214757376 23:36:23 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | Cycle 1(1): loaded http://localhost:43155/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43155/tests/kraken/driver.html?testName=ai-astar) 23:36:25 INFO - PROCESS | 30797 | RSS: Main: 256442368 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | __start_tp_report 23:36:25 INFO - PROCESS | 30797 | _x_x_mozilla_page_load 23:36:25 INFO - PROCESS | 30797 | _x_x_mozilla_page_load_details 23:36:25 INFO - PROCESS | 30797 | |i|pagename|runs| 23:36:25 INFO - PROCESS | 30797 | |0;ai-astar;113;107;110;110;121;110;110;112;110;109 23:36:25 INFO - PROCESS | 30797 | |1;audio-beat-detection;163;124;125;169;125;125;159;123;160;132 23:36:25 INFO - PROCESS | 30797 | |2;audio-dft;163;160;162;160;161;161;161;163;162;160 23:36:25 INFO - PROCESS | 30797 | |3;audio-fft;76;74;75;117;73;76;74;75;99;74 23:36:25 INFO - PROCESS | 30797 | |4;audio-oscillator;84;85;111;84;86;84;114;84;84;131 23:36:25 INFO - PROCESS | 30797 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;117;116;116 23:36:25 INFO - PROCESS | 30797 | |6;imaging-darkroom;147;148;145;145;146;147;146;145;146;146 23:36:25 INFO - PROCESS | 30797 | |7;imaging-desaturate;96;95;95;96;95;95;95;95;95;94 23:36:25 INFO - PROCESS | 30797 | |8;json-parse-financial;49;48;49;50;50;50;51;52;50;52 23:36:25 INFO - PROCESS | 30797 | |9;json-stringify-tinderbox;60;61;61;60;60;62;60;60;60;61 23:36:25 INFO - PROCESS | 30797 | |10;stanford-crypto-aes;78;76;77;77;77;78;77;77;107;76 23:36:25 INFO - PROCESS | 30797 | |11;stanford-crypto-ccm;128;117;120;116;125;125;126;119;124;126 23:36:25 INFO - PROCESS | 30797 | |12;stanford-crypto-pbkdf2;152;149;152;153;156;157;159;153;153;153 23:36:25 INFO - PROCESS | 30797 | |13;stanford-crypto-sha256-iterative;64;62;62;64;60;61;63;60;64;59 23:36:25 INFO - PROCESS | 30797 | __end_tp_report 23:36:25 INFO - PROCESS | 30797 | __start_cc_report 23:36:25 INFO - PROCESS | 30797 | _x_x_mozilla_cycle_collect,1595 23:36:25 INFO - PROCESS | 30797 | __end_cc_report 23:36:25 INFO - PROCESS | 30797 | __startTimestamp1469687785490__endTimestamp 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | ------- Summary: start ------- 23:36:25 INFO - PROCESS | 30797 | Number of tests: 14 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#0] ai-astar Cycles:10 Average:111.20 Median:110.00 stddev:3.79 (3.4%) stddev-sans-first:3.97 23:36:25 INFO - PROCESS | 30797 | Values: 113.0 107.0 110.0 110.0 121.0 110.0 110.0 112.0 110.0 109.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:145.50 stddev:19.47 (13.4%) stddev-sans-first:18.87 23:36:25 INFO - PROCESS | 30797 | Values: 163.0 124.0 125.0 169.0 125.0 125.0 159.0 123.0 160.0 132.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.16 (0.7%) stddev-sans-first:1.05 23:36:25 INFO - PROCESS | 30797 | Values: 163.0 160.0 162.0 160.0 161.0 161.0 161.0 163.0 162.0 160.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.00 stddev:14.73 (19.6%) stddev-sans-first:15.50 23:36:25 INFO - PROCESS | 30797 | Values: 76.0 74.0 75.0 117.0 73.0 76.0 74.0 75.0 99.0 74.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:84.00 stddev:17.31 (20.6%) stddev-sans-first:17.93 23:36:25 INFO - PROCESS | 30797 | Values: 84.0 85.0 111.0 84.0 86.0 84.0 114.0 84.0 84.0 131.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:36:25 INFO - PROCESS | 30797 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:1.00 23:36:25 INFO - PROCESS | 30797 | Values: 147.0 148.0 145.0 145.0 146.0 147.0 146.0 145.0 146.0 146.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 23:36:25 INFO - PROCESS | 30797 | Values: 96.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 94.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:1.29 (2.6%) stddev-sans-first:1.30 23:36:25 INFO - PROCESS | 30797 | Values: 49.0 48.0 49.0 50.0 50.0 50.0 51.0 52.0 50.0 52.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 23:36:25 INFO - PROCESS | 30797 | Values: 60.0 61.0 61.0 60.0 60.0 62.0 60.0 60.0 60.0 61.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:77.00 stddev:9.51 (12.4%) stddev-sans-first:10.06 23:36:25 INFO - PROCESS | 30797 | Values: 78.0 76.0 77.0 77.0 77.0 78.0 77.0 77.0 107.0 76.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#11] stanford-crypto-ccm Cycles:10 Average:122.60 Median:125.00 stddev:4.22 (3.4%) stddev-sans-first:4.00 23:36:25 INFO - PROCESS | 30797 | Values: 128.0 117.0 120.0 116.0 125.0 125.0 126.0 119.0 124.0 126.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:153.00 stddev:2.87 (1.9%) stddev-sans-first:2.98 23:36:25 INFO - PROCESS | 30797 | Values: 152.0 149.0 152.0 153.0 156.0 157.0 159.0 153.0 153.0 153.0 23:36:25 INFO - PROCESS | 30797 | 23:36:25 INFO - PROCESS | 30797 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.50 stddev:1.85 (3.0%) stddev-sans-first:1.80 23:36:25 INFO - PROCESS | 30797 | Values: 64.0 62.0 62.0 64.0 60.0 61.0 63.0 60.0 64.0 59.0 23:36:25 INFO - PROCESS | 30797 | -------- Summary: end -------- 23:36:25 INFO - PROCESS | 30797 | 23:36:26 INFO - TEST-INFO | 30797: exit 0 23:36:26 INFO - TEST-OK | kraken | took 59802ms 23:36:26 INFO - SUITE-END | took 1171s 23:36:26 INFO - Completed test suite (00:19:31) 23:36:26 INFO - javascript benchmark 23:36:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3311.139103833003, 3341.8329083545823, 3344.6153692325374, 3373.3590617782634, 3388.6780755828204, 5459.153831156167, 5548.361938377087, 5550.47270509302, 5560.9165862512045, 5566.972165139168, 5444.646098003637, 5473.781048758045, 5475.67145971242, 5475.890482190353, 5512.39363669996, 4878.097551952882, 4910.067087253428, 4939.703617782927, 4963.230699241605, 5012.498750124995, 4846.9030619387595, 4980.626453016038, 4998.150314446535, 5009.348784657989, 5009.874753131183, 4924.655274130812, 4944.777485013183, 4969.776360063788, 4983.900321993558, 4993.275974983625, 5060.645754797166, 5081.441041485434, 5081.466446023152, 5082.491750824925, 5097.108006098918, 4809.038192361541, 4852.369192005052, 4883.389576302962, 4989.101961646902, 5018.698878067291, 5277.102892508283, 5287.154055351125, 5290.629655924087, 5301.814436494743, 5348.786048558053, 5900.321463031763, 5903.380145084747, 5904.350521442638, 5905.350411454737, 5920.881582368372, 5801.88396232073, 5867.295924489046, 5878.177055212273, 5935.287765468128, 5950.345461999176, 5504.2294078829, 5555.083411237141, 5556.499915007628, 5558.277423934944, 5561.165825126263, 5786.0, 6067.0, 6071.241094863142, 6074.3925607438905, 6075.453209211192, 5736.225609542742, 5843.941560584425, 5860.531157507475, 5889.1755154278435, 5894.911576326359, 5926.911096333559, 5930.140129681258, 5938.109283607493, 5964.821055368346, 5971.671558064261, 5587.580931430159, 5700.315962084576, 5895.970520147372, 5912.201852749824, 5918.704064796743, 5734.340550836624, 5811.418858114154, 5814.622049566765, 5817.360090390053, 5855.970720146372, 4470.374147619336, 4481.5070342262325, 4487.125010622897, 4487.371767952489, 4520.32195170727, 3933.0757272041046, 3993.980030099831, 4012.057166565855, 4036.0515278909434, 4044.312466880637, 5579.023670857584, 5598.328200615871, 5604.327480702342, 5609.411011843779, 5627.887442251132, 5871.236739223874, 5889.558283128783, 5893.322267939242, 5900.911486327708, 5926.496247818897, 5471.781128754806, 5542.11326187808, 5583.078791999355, 5599.552035837123, 5609.775608975677, 4990.8253211137435, 5025.120603894377, 5025.296458495814, 5027.748612569357, 5029.17018691919, 6142.723577439026, 6145.723442445101, 6151.015837465984, 6159.876802463926, 6160.907586386208, 5677.914831277617, 5815.069588865761, 5858.5898987070495, 5874.647521148745, 5913.881722365442, 6110.7555697771595, 6120.663363514871, 6125.264968203844, 6144.723487442986, 6147.108669242845, 5759.798407055733, 5767.048437007847, 5767.884642307215, 5768.480836724715, 5777.133429985536, 5664.178694089416, 5780.046292361713, 5834.299884013945, 5840.474357307863, 5884.941150588611, 5710.857228569319, 5722.313322401338, 5735.942640573541, 5736.71316434185, 5751.511121554715, 5673.687947162781, 5716.114002329646, 5731.828045158652, 5732.426757324234, 5735.397783232867, 5524.696141712245, 5594.888102238014, 5620.381758006698, 5622.943770562242, 5685.77256909719, 5769.798057067983, 5781.508571771446, 5789.8263052108505, 5791.7683292667825, 5803.680797556175, 5450.0, 5634.211210430461, 5636.957162924872, 5645.294338207724, 5657.038303488499], "value": 5415.096736647475, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.3604379357881, 643.871225754849, 652.1228947066197, 657.6514447342909, 665.0689035350512, 33186.33627327452, 36005.09987250317, 36045.0, 46864.29703554441, 47781.28328075073, 48888.2666759998, 49254.50745492544, 49355.25967110496, 49389.25916111261, 49528.0, 8180.263776260135, 8948.73153805387, 9114.088591140866, 9117.316201284897, 9120.589573469177, 8264.0, 8552.486850788942, 8570.27152692022, 10596.364218146897, 10804.945975270151, 10994.230403871732, 11070.059044981146, 11075.280106793072, 11079.667609971713, 11083.556657733681, 8226.42415030948, 10922.508487118099, 10972.725681857937, 10996.780064398667, 11005.119590432787, 10579.523921423477, 10908.945455272673, 10920.781584368267, 10927.562897484091, 11311.547538098466, 41302.79348603238, 42107.0, 45242.77378613119, 45349.54650453486, 45400.3189952151, 3836.9616303836883, 3849.1147036181833, 3854.5759966403675, 3875.7286989910717, 4051.5948405159543, 34377.48433773495, 35441.11397215065, 35481.0, 35505.28989420223, 35564.82217588921, 4682.695624784378, 4775.982947177011, 4782.168789429852, 4820.68665536739, 4927.334809800667, 362995.0, 364677.0, 364687.0, 366635.0, 368679.0, 69430.65284673544, 70031.64984175046, 70045.0, 70072.0, 70094.64952675204, 40853.38719919204, 41678.58321416807, 41736.37395439071, 43779.562204378184, 74392.25607743853, 14352.49766258176, 14371.13773173592, 14440.494582689555, 14441.711165776836, 14464.0, 9582.568784404719, 10161.136303414145, 10162.745931351776, 10163.69508914719, 10258.333208341433, 21410.250641227794, 22753.203637872924, 22794.20220292315, 22799.544009119723, 22937.0, 8147.737100749393, 8349.499030058216, 8380.16198380157, 8525.829483410296, 8587.613557389932, 1033.178622994721, 1104.3153244987998, 1116.268843907242, 1193.540486912545, 1202.4348556178581, 34615.653843461245, 35063.82468087643, 35099.473507897404, 35126.29747405038, 35313.470297945554, 1184.5972369394447, 1189.1259923955886, 1198.4966314148078, 1213.059878594079, 1250.9812352814715, 17567.912160439115, 18113.54716132081, 18324.633507329778, 18326.725099123523, 18439.90780046091, 627980.0, 632450.0, 634472.0, 636184.0, 642243.0, 64717.67641161764, 66062.0, 66114.33885661178, 66214.33785662081, 66247.33752662412, 1298.4741179822188, 1403.3544569498144, 1419.06341814402, 1436.5977526292654, 1468.941242350294, 34530.0, 35727.46408803818, 35790.64209357873, 35886.0, 35994.0, 54787.72606136944, 55605.721971391504, 55638.0, 55662.721686391305, 55687.0, 430597.0, 435786.0, 437284.0, 438270.0, 438308.0, 27393.178204653894, 28011.71988280091, 28083.43833123367, 28376.716232837407, 29839.0, 51779.482205178974, 52459.0, 53131.4686853142, 53943.0, 54168.0, 43823.56176438322, 43863.78068109639, 44000.0, 44545.33182002338, 44985.0, 43386.0, 43977.0, 44381.0, 44401.11197776091, 45612.315815263464, 44589.0, 44705.10589788251, 45007.54992450034, 45134.77432612816, 45313.320300194864, 37826.810865945496, 39881.40177897276, 39967.60032399639, 40628.0, 40997.795011024755, 29717.554236686035, 29897.09119029197, 29961.0, 30264.243393915327, 30559.388812223187], "value": 21494.1307735134, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.42445120094254, 382.8334674416629, 383.0864830173285, 398.631266078877, 404.56509252553496, 18333.816661833378, 19279.51801204967, 20637.587248255026, 20707.171713131476, 20738.792612073874, 11080.113590912728, 11551.480183391746, 11567.768644627102, 11576.53693852247, 15737.291821868015, 5173.793048278064, 5363.892722145555, 5391.245225668409, 5393.487618676239, 5397.892042159155, 3948.3287841066945, 5572.749226284827, 5862.325832529271, 5867.794627188049, 5956.553258505608, 5867.11993201019, 5919.319278283009, 5928.40715928408, 5935.940640593603, 5951.970240148793, 5573.5819813514145, 5686.2039314496005, 5902.852428689274, 5904.616199947011, 5913.615614985012, 3824.2733880562605, 5447.482489163563, 5765.308163020464, 5769.538436925036, 5879.029960056586, 13766.587002389944, 13793.862061379357, 17247.65504689899, 24269.635955460682, 24296.271111866674, 2557.0794513974956, 2566.089038391363, 2623.3048242215827, 2822.280318518785, 2848.3591191981845, 9909.75225619358, 10170.491475426199, 14604.196769177692, 14653.92673036628, 14777.408903643842, 2010.497375656086, 2015.0629957070032, 2040.2655044184082, 2075.7509098908076, 2087.4468265909545, 88099.0, 89815.55092224627, 90231.54884225603, 92026.0, 93645.0, 10774.245802793886, 10948.562057517769, 11078.67347856943, 11169.664910052712, 11284.943575282236, 15314.310856011507, 15382.923085384502, 15437.536873893801, 15449.768253476206, 15474.22628868552, 7057.717691292393, 7359.337659610661, 7432.368248698921, 7497.962510187414, 7507.099148102154, 6721.831954201184, 6850.794476165723, 6897.827554311182, 6924.23833378328, 6927.064846245793, 4747.359106520645, 4796.544328288807, 4810.39870016248, 4879.072976134523, 4920.606351491872, 1750.5740951167222, 1763.2153691607368, 1830.283689000907, 1958.8237058664788, 1970.763508378975, 983.3559018842669, 1075.9139268858473, 1080.5947769586405, 1088.4666513408372, 1161.3438407299848, 18787.24851005972, 19422.240361547592, 19621.117049732795, 19713.112909919088, 19772.20911163566, 1038.1072277841051, 1043.6503771236721, 1055.9788804223872, 1076.1283360478037, 1096.6764804382726, 10761.408122553335, 11037.889621103846, 11075.944620276847, 11139.387333696563, 11158.721031974266, 508446.0, 528009.0, 535377.0, 551791.0, 556217.0, 97361.0, 97368.51315743518, 98238.50880745694, 98367.0, 98507.0, 1030.3199888073984, 1049.7008352619466, 1055.8785739639886, 1073.1361254190297, 1077.6605369308643, 16666.50000499987, 17295.481135565955, 17298.221580028676, 17468.82531174672, 18111.184996675445, 38415.807920961335, 40931.1813763729, 42057.78971105125, 42218.36672449977, 42749.145017100105, 58741.0, 60464.09303860534, 61047.38952610417, 61591.38408615856, 62593.68703156455, 7269.927300726925, 7765.262300081483, 7966.960165199137, 8820.2061814437, 8846.336524760798, 8948.73153805387, 12882.871171288167, 13019.544315948897, 13348.0, 13657.658558536115, 11783.175177737476, 11914.285142891455, 12202.511899523919, 12220.572279970158, 12235.0, 11683.707907302385, 11734.94132529332, 11790.705232368917, 11870.762584748429, 12260.14179007461, 12038.337891415706, 12050.216735912138, 12101.031917446759, 12116.818247726467, 12260.38698065111, 8470.32237421017, 8638.17937295956, 8702.173293537104, 8712.346574006848, 8818.0, 11412.144089193178, 11986.101042421678, 12141.757164856477, 12186.817197742219, 12419.130660854014], "value": 9207.877863837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1452.8038714773502, 1550.775137605047, 1586.6747316800056, 1764.8145098097937, 1917.453525745162, 9131.817363652732, 9507.096825801538, 9536.952315238423, 9827.164691001255, 9856.063673950981, 9581.233501319895, 9752.609895604184, 9763.804723905536, 9768.218542516599, 9778.413295202294, 2094.2984100326407, 2150.483883867876, 2154.062982602565, 2419.262125051854, 2450.375154335642, 2394.521095780842, 2470.654108424822, 2471.9505609887883, 2489.65144879717, 2493.6758221431187, 2383.0467812874817, 2395.9880200598973, 2397.077125306752, 2401.2916189724074, 2423.3941514621347, 1997.5106099005836, 2064.721262629541, 2432.027189124347, 2436.013414567103, 2438.4879175373103, 2090.247510896076, 2321.0831721469926, 2372.4899146683374, 2374.7625237476113, 2392.9042838286446, 9549.188318992825, 9593.568289426923, 9605.13553780163, 9607.90392096077, 9632.47021413822, 5343.385510666284, 5413.566914646819, 5474.644148130398, 5488.615796894219, 5489.643173193769, 3174.555562221291, 3213.8393080346123, 3263.8694452221885, 3473.0796338970194, 3491.109767009422, 1043.2801367056707, 1087.0922779479145, 1101.8126918423807, 1119.3507765495992, 1123.7696272264166, 13635.113717608225, 13692.383842727102, 13698.109622874481, 13701.931490342484, 13726.862731372657, 4526.95473045272, 4610.838620648328, 4686.437627484724, 4753.952460475351, 4769.546893045155, 11342.035926946137, 11346.262492937933, 11356.148288878367, 11376.260543064674, 11389.772204555862, 5252.9737351313, 5265.21021846726, 5268.394134674561, 5275.4460781618145, 5297.735113244323, 4304.16068866568, 4630.629549636021, 4684.672072954929, 4713.8350157745, 4919.458859525521, 4143.81352839123, 4164.604362585549, 4191.392248124005, 4197.223513649985, 4235.597618226264, 1221.157401393036, 1224.9326287054298, 1234.7221875078035, 1243.5771837575269, 1245.8691837356946, 912.5391677203103, 951.7192428233687, 976.321456587679, 981.097390400833, 994.3834822410151, 6345.777897773556, 6466.579672321378, 6468.15913931186, 6509.251436084816, 6600.702968366435, 451.68607817566476, 461.5107985535343, 467.5721714631142, 475.3748820301293, 478.58124141376294, 4354.129174165179, 4381.364702118175, 4428.955710442919, 4506.526814684476, 4539.659525535598, 11787.469563869647, 12221.144519883523, 12221.450034748457, 12223.755524889453, 12232.963122169662, 11435.942820285845, 11912.404379780975, 11912.702182445508, 12153.088518361159, 12224.1443098984, 8887.15572020657, 8951.0, 9006.234470070118, 9009.954950225338, 9017.909820901707, 6360.93639063622, 6729.55159588779, 6735.225449073419, 6765.695543700447, 6865.519413640997, 10774.946125269636, 10946.233763636788, 11240.9321114494, 11244.212905096892, 11339.659810205707, 11263.436828158723, 11364.659060228207, 11407.315561066363, 11502.654920352403, 11654.475548600503, 7879.960600196962, 8327.958360208402, 8451.366147538836, 8457.915420845713, 8613.3109351253, 9566.378185417925, 9605.18355939753, 9611.903880961101, 9699.757506062122, 9721.173700235558, 8436.32509399259, 8635.827283454422, 8653.956730216309, 8676.175763302475, 8799.47203167812, 8158.184181581794, 8242.670293188445, 8308.543030133158, 8388.958055209685, 8414.242718155396, 7796.883046754417, 7896.802579935547, 7932.960335198287, 7935.523868567904, 7936.682532698858, 5479.6164268500825, 5575.163725441054, 5688.0, 5736.42635736423, 5779.364269930388, 5407.675539467645, 5461.34463864323, 5474.616776825584, 5489.258950041693, 5578.442155784389], "value": 4817.863292704775, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.93981203759245, 305.73859350255543, 310.6924145096354, 324.3529159327141, 325.2632786738037, 5271.42014378418, 5377.919331210025, 5435.619506634537, 5457.399686034538, 5493.917591236134, 5374.3013408256875, 5405.108157154074, 5410.08028635132, 5424.349078110632, 5440.972795136028, 4240.787960601973, 4249.3625956106525, 4251.0, 4259.5740425957465, 4294.699371044028, 3969.503812023497, 4075.694322925792, 4169.687273454488, 4249.383839343293, 4260.957390426102, 4134.676903390916, 4152.335626299792, 4173.165366926612, 4177.120295866857, 4189.392538081976, 4143.295639741236, 4160.791960401998, 4170.77060761658, 4189.07840275139, 4222.641075508585, 3591.712662986955, 3634.4889805387356, 3683.5027271318295, 3685.594584595692, 3693.519842420468, 5153.742312884341, 5160.200168973815, 5184.11869982104, 5184.533391994739, 5186.377634683862, 4704.788284527204, 4745.501722319142, 4747.667663263573, 4785.846369133904, 4790.353302304214, 5447.101228297326, 5567.691592475765, 5568.3596386415675, 5598.468145526169, 5613.747381367807, 3976.0517346500296, 3987.960120398817, 4028.8388464461386, 4029.9245888367514, 4040.7777572233517, 5741.684207368593, 5760.855978600526, 5828.912566311508, 5856.76572937082, 5860.04067945689, 3999.680025597945, 4065.10567675111, 4091.0, 4101.507819061731, 4103.8974025649595, 4132.752034877917, 4152.833886644561, 4162.459889840718, 4163.562825903297, 4165.354370072643, 4742.5020372861045, 4744.5492678195515, 4751.83368582098, 4781.521847815191, 4781.545753153515, 5439.0, 5647.463490968352, 5658.97170514153, 5669.6314739541795, 5704.286964129484, 1395.0, 1408.4929425406845, 1412.576227131877, 1426.978595321071, 1433.9426422943175, 895.4895709445553, 929.934904556688, 931.189864817613, 942.641796117471, 946.6828612414918, 4877.244027175793, 4892.68197567157, 4901.264810278415, 4955.776990035457, 5020.698758074527, 5112.089251722436, 5145.559243411851, 5148.760289274178, 5209.244659524347, 5239.821040265986, 4707.693999890064, 4917.311576379309, 4930.630202734809, 4949.90100197994, 4998.075356059141, 2745.492083964473, 2753.655793025872, 2786.4845003674386, 2803.9158825235277, 2804.765903002672, 5302.575793936476, 5351.946480535223, 5384.838454846283, 5403.594730395236, 5410.648307860055, 5051.898962020812, 5227.398849132398, 5274.789008439696, 5279.465856388955, 5301.708406037628, 5191.688498690015, 5382.946170538323, 5390.568754499709, 5396.730163491889, 5404.972975135099, 5099.745012749274, 5102.183650615883, 5120.513551212629, 5155.922661160011, 5157.535821776059, 4842.322074909586, 5076.36545431821, 5094.2103973884105, 5099.628672667228, 5099.79600815978, 5297.682139071668, 5299.761510731949, 5306.389765177054, 5317.867053323698, 5357.571394288369, 3852.7880966545986, 3959.9010024749614, 4057.046594050337, 4112.91774164521, 4129.8967525812095, 5738.68437235956, 5755.913661295168, 5756.186801157622, 5768.394318596487, 5779.266220134027, 5414.35027796667, 5540.198788448977, 5576.470235327637, 5600.57995650336, 5632.802851900164, 5532.446755324597, 5573.7770489179975, 5583.664980101207, 5590.888182236251, 5594.62931581765, 5472.455308281668, 5492.560595152298, 5495.175723641486, 5503.752331145028, 5504.284443022541, 5383.757730902041, 5581.330240371181, 5600.327960644749, 5687.402822703557, 5741.0, 5548.389677135431, 5582.720863956867, 5590.133529302964, 5596.832095037155, 5597.916031259453], "value": 4147.155361702577, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.8437285983513, 743.6430513353594, 821.8849361089444, 885.8228354329134, 899.6851102114259, 13288.800667989975, 13868.167909925387, 13917.443302267922, 13932.094413863104, 13935.860641393583, 12634.81047784284, 12659.55691550796, 12949.935250323759, 12951.352432378391, 12960.287184204864, 707.405779145519, 851.0723311590365, 875.7022612311813, 877.3419935048713, 877.5743764274322, 870.8084221471266, 874.7681864305964, 876.8947726272856, 877.324460165672, 877.8332116897801, 753.6646192444365, 918.4076270805326, 918.4168053286145, 921.0283151275402, 921.5714692667941, 763.3969164360149, 767.5548182054395, 886.410536992899, 908.8318661047728, 910.5856835139996, 668.8762578922915, 668.9163854518185, 671.9126513553256, 673.9764108256187, 675.1965161457849, 13179.60461186166, 13212.801807972792, 13221.140625859289, 13230.338483075808, 13231.073824832265, 14768.0, 15011.624709382244, 15048.473303434328, 15180.620484487865, 15312.693746125127, 13256.40346184424, 13400.865991340157, 13744.862551374557, 13779.862201377959, 13900.374483148282, 4730.385049943486, 4746.572808447257, 4786.473487916326, 4799.952000479985, 4904.877378065541, 74274.0, 74492.25507744908, 74593.6270318645, 75126.24873751248, 79850.60074699647, 12270.631881043404, 12318.938405307916, 12408.0, 12593.622191334274, 12599.748005040032, 23058.538829223322, 23164.305070847564, 23235.53528929412, 23238.65142022871, 23246.767532324797, 8871.955640221757, 9020.278377729766, 9047.592858321392, 9190.816183676288, 9325.673601424054, 4783.521647835189, 4867.0995865764235, 4871.48849370811, 5015.4733752956145, 5064.5948324134715, 3713.814309284525, 3822.4266360046217, 4092.9386059209137, 4270.274053410928, 4288.09949910516, 736.8415790604938, 753.179034852003, 769.5844244108231, 769.7113582406597, 774.4694884004475, 685.9588424694534, 688.0745397440435, 688.9827754306183, 719.4891626944927, 780.6448066129898, 9923.255755818342, 10558.260921735558, 10635.787284254271, 10649.9467502662, 10680.412577308323, 749.4528993834531, 775.7401270574308, 783.7531177679012, 796.3617587480624, 798.9001374828147, 5104.897902041938, 5105.185715421953, 5159.509846564571, 5205.046537316544, 5211.270422140903, 85153.0, 127764.36117819352, 128440.0, 130221.34889325683, 132579.0, 69428.6528567354, 70585.29414705787, 72771.63614181895, 111591.0, 112269.43865280785, 98837.0, 98867.0, 99237.50381248193, 100033.49983250037, 100734.49632751789, 9631.325807193429, 9652.27607929422, 9678.870648457329, 9878.753031174278, 9890.159336456481, 41226.175476490906, 55193.44806551883, 55556.0, 55747.721261395054, 57214.42785572091, 81787.5910620443, 81870.59064704638, 82336.0, 83449.58275208584, 85741.57129214314, 12469.750604987668, 12555.811662825248, 12864.485420583072, 12903.354832258537, 13525.526606568723, 15494.845051549792, 15553.455629052929, 15683.215839207765, 16255.0, 16351.67296654084, 14738.63153421173, 14845.076612661358, 15162.3176957035, 15724.449644262397, 15726.370945162524, 14388.28058597087, 14417.42330306776, 14710.705785884436, 14758.63103422423, 15688.137152456724, 13227.206367617973, 13474.86525134736, 13695.315234238049, 13930.860691392956, 14352.210628415538, 10928.50821713009, 10967.670969870916, 11019.944900275448, 11145.777084458103, 11357.716057098638, 11085.556577736801, 11257.383047088031, 11449.313041217554, 11462.94268528652, 11537.711557211136], "value": 7920.606290741781, "unit": "score"}], "name": "dromaeo_css", "value": 7440.166378778786, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 110.0, 110.0, 121.0, 110.0, 110.0, 112.0, 110.0, 109.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 124.0, 125.0, 169.0, 125.0, 125.0, 159.0, 123.0, 160.0, 132.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 162.0, 160.0, 161.0, 161.0, 161.0, 163.0, 162.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 117.0, 73.0, 76.0, 74.0, 75.0, 99.0, 74.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 111.0, 84.0, 86.0, 84.0, 114.0, 84.0, 84.0, 131.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 145.0, 145.0, 146.0, 147.0, 146.0, 145.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 49.0, 50.0, 50.0, 50.0, 51.0, 52.0, 50.0, 52.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 60.0, 60.0, 62.0, 60.0, 60.0, 60.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 77.0, 77.0, 77.0, 78.0, 77.0, 77.0, 107.0, 76.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 117.0, 120.0, 116.0, 125.0, 125.0, 126.0, 119.0, 124.0, 126.0], "value": 122.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 149.0, 152.0, 153.0, 156.0, 157.0, 159.0, 153.0, 153.0, 153.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 64.0, 60.0, 61.0, 63.0, 60.0, 64.0, 59.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1475.6000000000001, "alertThreshold": 2.0}]} 23:36:26 INFO - Return code: 0 23:36:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:36:26 ERROR - # TBPL SUCCESS # 23:36:26 INFO - Running post-action listener: _resource_record_post_action 23:36:26 INFO - ##### 23:36:26 INFO - ##### Finished run-tests step (success) 23:36:26 INFO - ##### 23:36:26 INFO - Running post-run listener: _resource_record_post_run 23:36:26 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 389906432; Read time: 16; Write time: 236288 23:36:26 INFO - TinderboxPrint: CPU usage
13.1% 23:36:26 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 23:36:26 INFO - TinderboxPrint: I/O write bytes / time
389,906,432 / 236,288 23:36:26 INFO - TinderboxPrint: CPU idle
8,236.6 (86.9%) 23:36:26 INFO - TinderboxPrint: CPU user
1,200.9 (12.7%) 23:36:26 INFO - TinderboxPrint: Swap in / out
0 / 0 23:36:26 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 130871296; Read time: 0; Write time: 90540 23:36:26 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 244109312; Read time: 16; Write time: 130728 23:36:27 INFO - Running post-run listener: _upload_blobber_files 23:36:27 INFO - Blob upload gear active. 23:36:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:36:27 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 23:36:27 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'] 23:36:27 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 23:36:27 INFO - (blobuploader) - INFO - Open directory for files ... 23:36:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:36:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:36:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:28 INFO - (blobuploader) - INFO - Done attempting. 23:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:36:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:36 INFO - (blobuploader) - INFO - Done attempting. 23:36:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:36:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:36:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:36:37 INFO - (blobuploader) - INFO - Done attempting. 23:36:37 INFO - (blobuploader) - INFO - Iteration through files over. 23:36:37 INFO - Return code: 0 23:36:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:36:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:36:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49019c6de5504ad6f31a3e45afa224201f54d1872f22187a5dd5a1e01a451c63921809ac8777150317c00fbb0762ff4041490ff45d6abb5145422f8057933322", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3c0524848eb5923176700fdd078cd0d5d59f045115539fb06795f558ba1770c130359e834d89d43b5dd9c6afbe147ffba4044efb5f741cd1b6240594fb3cee3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00d1a37a0b066762ad32db799ca048b0d317e611bbaa42fae99ae4deaba577c8dcb24cf2d3497ce77944ff07380dbf20759f69b97c195be9d264159caa94ec85"} 23:36:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:36:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:36:37 INFO - Contents: 23:36:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49019c6de5504ad6f31a3e45afa224201f54d1872f22187a5dd5a1e01a451c63921809ac8777150317c00fbb0762ff4041490ff45d6abb5145422f8057933322", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3c0524848eb5923176700fdd078cd0d5d59f045115539fb06795f558ba1770c130359e834d89d43b5dd9c6afbe147ffba4044efb5f741cd1b6240594fb3cee3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00d1a37a0b066762ad32db799ca048b0d317e611bbaa42fae99ae4deaba577c8dcb24cf2d3497ce77944ff07380dbf20759f69b97c195be9d264159caa94ec85"} 23:36:37 INFO - Running post-run listener: copy_logs_to_upload_dir 23:36:37 INFO - Copying logs to upload dir... 23:36:37 INFO - mkdir: /builds/slave/test/build/upload/logs 23:36:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.877060 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-27 23:36:37.370950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 23:36:37.374775) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174 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/49019c6de5504ad6f31a3e45afa224201f54d1872f22187a5dd5a1e01a451c63921809ac8777150317c00fbb0762ff4041490ff45d6abb5145422f8057933322", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3c0524848eb5923176700fdd078cd0d5d59f045115539fb06795f558ba1770c130359e834d89d43b5dd9c6afbe147ffba4044efb5f741cd1b6240594fb3cee3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00d1a37a0b066762ad32db799ca048b0d317e611bbaa42fae99ae4deaba577c8dcb24cf2d3497ce77944ff07380dbf20759f69b97c195be9d264159caa94ec85"} build_url:https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011351 build_url: 'https://queue.taskcluster.net/v1/task/Es3opUeJQsatstOdhznFXw/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/49019c6de5504ad6f31a3e45afa224201f54d1872f22187a5dd5a1e01a451c63921809ac8777150317c00fbb0762ff4041490ff45d6abb5145422f8057933322", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3c0524848eb5923176700fdd078cd0d5d59f045115539fb06795f558ba1770c130359e834d89d43b5dd9c6afbe147ffba4044efb5f741cd1b6240594fb3cee3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/00d1a37a0b066762ad32db799ca048b0d317e611bbaa42fae99ae4deaba577c8dcb24cf2d3497ce77944ff07380dbf20759f69b97c195be9d264159caa94ec85"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 23:36:37.402569) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 23:36:37.402959) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1469685524.255877-777140174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 23:36:37.489138) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 23:36:37.489903) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 23:36:37.490544) ========= ========= Total master_lag: 0.18 =========