builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-017 starttime: 1466140765.47 results: success (0) buildid: 20160616193134 builduid: 392a0460a4534879a5ae53f7a2d8ce93 revision: 5f95858f8ddf21ea2271a12810332efd09eff138 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:25.466060) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:25.466600) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:25.466919) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:25.482648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:25.482956) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-16 22:19:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 19.2M=0.001s 2016-06-16 22:19:25 (19.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173370 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:25.670703) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:25.671063) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016581 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:25.724307) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-16 22:19:25.724785) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5f95858f8ddf21ea2271a12810332efd09eff138 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5f95858f8ddf21ea2271a12810332efd09eff138 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-16 22:19:25,943 truncating revision to first 12 chars 2016-06-16 22:19:25,943 Setting DEBUG logging. 2016-06-16 22:19:25,943 attempt 1/10 2016-06-16 22:19:25,943 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5f95858f8ddf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-16 22:19:27,016 unpacking tar archive at: mozilla-central-5f95858f8ddf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.443110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-16 22:19:27.183380) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:27.183899) ========= script_repo_revision: 5f95858f8ddf21ea2271a12810332efd09eff138 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:27.184407) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:27.184812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 22:19:27.197419) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-16 22:19:27.197917) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:19:27 INFO - MultiFileLogger online at 20160616 22:19:27 in /builds/slave/test 22:19:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:19:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:19:27 INFO - {'append_to_log': False, 22:19:27 INFO - 'base_work_dir': '/builds/slave/test', 22:19:27 INFO - 'blob_upload_branch': 'Firefox', 22:19:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:19:27 INFO - 'branch': 'Firefox', 22:19:27 INFO - 'buildbot_json_path': 'buildprops.json', 22:19:27 INFO - 'config_files': ('talos/linux_config.py',), 22:19:27 INFO - 'default_actions': ('clobber', 22:19:27 INFO - 'read-buildbot-config', 22:19:27 INFO - 'download-and-extract', 22:19:27 INFO - 'populate-webroot', 22:19:27 INFO - 'create-virtualenv', 22:19:27 INFO - 'install', 22:19:27 INFO - 'run-tests'), 22:19:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:19:27 INFO - 'download_minidump_stackwalk': True, 22:19:27 INFO - 'download_symbols': 'ondemand', 22:19:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:19:27 INFO - 'tooltool.py': '/tools/tooltool.py', 22:19:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:19:27 INFO - '/tools/misc-python/virtualenv.py')}, 22:19:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:19:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:19:27 INFO - 'installer_path': 'installer.exe', 22:19:27 INFO - 'log_level': 'info', 22:19:27 INFO - 'log_name': 'talos', 22:19:27 INFO - 'log_to_console': True, 22:19:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:19:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:19:27 INFO - 'opt_config_files': (), 22:19:27 INFO - 'pip_index': False, 22:19:27 INFO - 'sps_profile': False, 22:19:27 INFO - 'sps_profile_interval': 0, 22:19:27 INFO - 'suite': 'dromaeojs-e10s', 22:19:27 INFO - 'system_bits': '32', 22:19:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:19:27 INFO - 'title': 'talos-linux64-ix-017', 22:19:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:19:27 INFO - 'use_talos_json': True, 22:19:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:19:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:19:27 INFO - 'work_dir': 'build'} 22:19:27 INFO - ##### 22:19:27 INFO - ##### Running clobber step. 22:19:27 INFO - ##### 22:19:27 INFO - Running pre-action listener: _resource_record_pre_action 22:19:27 INFO - Running main action method: clobber 22:19:27 INFO - rmtree: /builds/slave/test/build 22:19:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:19:27 INFO - Running post-action listener: _resource_record_post_action 22:19:27 INFO - ##### 22:19:27 INFO - ##### Finished clobber step (success) 22:19:27 INFO - ##### 22:19:27 INFO - ##### 22:19:27 INFO - ##### Running read-buildbot-config step. 22:19:27 INFO - ##### 22:19:27 INFO - Running pre-action listener: _resource_record_pre_action 22:19:27 INFO - Running main action method: read_buildbot_config 22:19:27 INFO - Using buildbot properties: 22:19:27 INFO - { 22:19:27 INFO - "project": "", 22:19:27 INFO - "product": "firefox", 22:19:27 INFO - "script_repo_revision": "production", 22:19:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:19:27 INFO - "repository": "", 22:19:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 22:19:27 INFO - "buildid": "20160616193134", 22:19:27 INFO - "pgo_build": "True", 22:19:27 INFO - "basedir": "/builds/slave/test", 22:19:27 INFO - "buildnumber": 254, 22:19:27 INFO - "slavename": "talos-linux64-ix-017", 22:19:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:19:27 INFO - "platform": "ubuntu64_hw", 22:19:27 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:19:27 INFO - "branch": "mozilla-central", 22:19:27 INFO - "repo_path": "mozilla-central", 22:19:27 INFO - "revision": "5f95858f8ddf21ea2271a12810332efd09eff138", 22:19:27 INFO - "stage_platform": "linux64-pgo", 22:19:27 INFO - "builduid": "392a0460a4534879a5ae53f7a2d8ce93", 22:19:27 INFO - "slavebuilddir": "test" 22:19:27 INFO - } 22:19:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:19:27 INFO - Running post-action listener: _resource_record_post_action 22:19:27 INFO - ##### 22:19:27 INFO - ##### Finished read-buildbot-config step (success) 22:19:27 INFO - ##### 22:19:27 INFO - ##### 22:19:27 INFO - ##### Running download-and-extract step. 22:19:27 INFO - ##### 22:19:27 INFO - Running pre-action listener: _resource_record_pre_action 22:19:27 INFO - Running main action method: download_and_extract 22:19:27 INFO - mkdir: /builds/slave/test/build/tests 22:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:27 INFO - https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:19:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:19:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:19:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:19:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:19:28 INFO - Downloaded 1361 bytes. 22:19:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:19:28 INFO - Using the following test package requirements: 22:19:28 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:19:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:19:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:19:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:28 INFO - u'jsshell-linux-x86_64.zip'], 22:19:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:19:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:19:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:19:28 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:19:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:19:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:19:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:19:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:28 INFO - https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:19:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:19:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:19:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:19:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:19:29 INFO - Downloaded 24220929 bytes. 22:19:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:19:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:19:31 INFO - Return code: 0 22:19:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:31 INFO - https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:19:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:19:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:19:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:19:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:19:37 INFO - Downloaded 24220929 bytes. 22:19:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:19:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:19:38 INFO - Return code: 0 22:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:38 INFO - https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:19:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:19:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:19:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:19:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:19:40 INFO - Downloaded 14405384 bytes. 22:19:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:19:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:19:40 INFO - Return code: 0 22:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:40 INFO - https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:19:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:19:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:19:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:19:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:19:41 INFO - Downloaded 59397990 bytes. 22:19:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:19:41 INFO - mkdir: /builds/slave/test/properties 22:19:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:19:41 INFO - Writing to file /builds/slave/test/properties/build_url 22:19:41 INFO - Contents: 22:19:41 INFO - build_url:https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:19:42 INFO - Running post-action listener: _resource_record_post_action 22:19:42 INFO - Running post-action listener: set_extra_try_arguments 22:19:42 INFO - ##### 22:19:42 INFO - ##### Finished download-and-extract step (success) 22:19:42 INFO - ##### 22:19:42 INFO - ##### 22:19:42 INFO - ##### Running populate-webroot step. 22:19:42 INFO - ##### 22:19:42 INFO - Running pre-action listener: _resource_record_pre_action 22:19:42 INFO - Running main action method: populate_webroot 22:19:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:19:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:19:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:19:42 INFO - '--tppagecycles', 22:19:42 INFO - '7'], 22:19:42 INFO - 'tests': ['tsvgm']}}, 22:19:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:19:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:19:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:19:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:19:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:42 INFO - 'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:19:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:19:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:42 INFO - 'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['damp', 'tps']}, 22:19:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:42 INFO - 'tests': ['damp', 'tps']}, 22:19:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['dromaeo_dom']}, 22:19:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:19:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['basic_compositor_video']}, 22:19:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:19:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['a11yr', 22:19:42 INFO - 'ts_paint', 22:19:42 INFO - 'tpaint', 22:19:42 INFO - 'sessionrestore', 22:19:42 INFO - 'sessionrestore_no_auto_restore', 22:19:42 INFO - 'tabpaint']}, 22:19:42 INFO - 'other-e10s': {'tests': ['a11yr', 22:19:42 INFO - 'ts_paint', 22:19:42 INFO - 'tpaint', 22:19:42 INFO - 'sessionrestore', 22:19:42 INFO - 'sessionrestore_no_auto_restore', 22:19:42 INFO - 'tabpaint']}, 22:19:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:19:42 INFO - 'ts_paint', 22:19:42 INFO - 'tpaint', 22:19:42 INFO - 'sessionrestore', 22:19:42 INFO - 'sessionrestore_no_auto_restore', 22:19:42 INFO - 'tabpaint']}, 22:19:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:19:42 INFO - 'ts_paint', 22:19:42 INFO - 'tpaint', 22:19:42 INFO - 'sessionrestore', 22:19:42 INFO - 'sessionrestore_no_auto_restore', 22:19:42 INFO - 'tabpaint']}, 22:19:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['a11yr', 22:19:42 INFO - 'ts_paint', 22:19:42 INFO - 'tpaint', 22:19:42 INFO - 'sessionrestore', 22:19:42 INFO - 'sessionrestore_no_auto_restore', 22:19:42 INFO - 'tabpaint']}, 22:19:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['a11yr', 22:19:42 INFO - 'ts_paint', 22:19:42 INFO - 'tpaint', 22:19:42 INFO - 'sessionrestore', 22:19:42 INFO - 'sessionrestore_no_auto_restore', 22:19:42 INFO - 'tabpaint']}, 22:19:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['tsvgx', 22:19:42 INFO - 'tsvgr_opacity', 22:19:42 INFO - 'tart', 22:19:42 INFO - 'tscrollx', 22:19:42 INFO - 'cart']}, 22:19:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:19:42 INFO - 'tsvgr_opacity', 22:19:42 INFO - 'tart', 22:19:42 INFO - 'tscrollx', 22:19:42 INFO - 'cart']}, 22:19:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:42 INFO - 'talos_options': ['--disable-e10s'], 22:19:42 INFO - 'tests': ['tp5o']}, 22:19:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:42 INFO - 'tests': ['tp5o']}, 22:19:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:42 INFO - 'talos_options': ['--disable-e10s', 22:19:42 INFO - '--xperf_path', 22:19:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:19:42 INFO - 'tests': ['tp5n']}, 22:19:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:19:42 INFO - 'talos_options': ['--xperf_path', 22:19:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:19:42 INFO - 'tests': ['tp5n']}}, 22:19:42 INFO - 'talos.zip': {'path': '', 22:19:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:19:42 INFO - Running post-action listener: _resource_record_post_action 22:19:42 INFO - ##### 22:19:42 INFO - ##### Finished populate-webroot step (success) 22:19:42 INFO - ##### 22:19:42 INFO - ##### 22:19:42 INFO - ##### Running create-virtualenv step. 22:19:42 INFO - ##### 22:19:42 INFO - Running pre-action listener: _resource_record_pre_action 22:19:42 INFO - Running main action method: create_virtualenv 22:19:42 INFO - Creating virtualenv /builds/slave/test/build/venv 22:19:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:19:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:19:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:19:42 INFO - Using real prefix '/usr' 22:19:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:19:43 INFO - Installing distribute.............................................................................................................................................................................................done. 22:19:45 INFO - Installing pip.................done. 22:19:45 INFO - Return code: 0 22:19:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:45 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:19:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:19:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:19:45 INFO - Using env: {'DISPLAY': ':0', 22:19:45 INFO - 'HOME': '/home/cltbld', 22:19:45 INFO - 'LANG': 'en_US.UTF-8', 22:19:45 INFO - 'LANGUAGE': 'en_US:en', 22:19:45 INFO - 'LOGNAME': 'cltbld', 22:19:45 INFO - 'MAIL': '/var/mail/cltbld', 22:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:45 INFO - 'MOZ_NO_REMOTE': '1', 22:19:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:45 INFO - 'NO_EM_RESTART': '1', 22:19:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:45 INFO - 'PWD': '/builds/slave/test', 22:19:45 INFO - 'SHELL': '/bin/bash', 22:19:45 INFO - 'SHLVL': '1', 22:19:45 INFO - 'TERM': 'linux', 22:19:45 INFO - 'TMOUT': '86400', 22:19:45 INFO - 'USER': 'cltbld', 22:19:45 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:45 INFO - '_': '/tools/buildbot/bin/python'} 22:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:45 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:19:45 INFO - Cleaning up... 22:19:45 INFO - Return code: 0 22:19:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:45 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:19:45 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 22:19:45 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 22:19:45 INFO - Using env: {'DISPLAY': ':0', 22:19:45 INFO - 'HOME': '/home/cltbld', 22:19:45 INFO - 'LANG': 'en_US.UTF-8', 22:19:45 INFO - 'LANGUAGE': 'en_US:en', 22:19:45 INFO - 'LOGNAME': 'cltbld', 22:19:45 INFO - 'MAIL': '/var/mail/cltbld', 22:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:45 INFO - 'MOZ_NO_REMOTE': '1', 22:19:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:45 INFO - 'NO_EM_RESTART': '1', 22:19:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:45 INFO - 'PWD': '/builds/slave/test', 22:19:45 INFO - 'SHELL': '/bin/bash', 22:19:45 INFO - 'SHLVL': '1', 22:19:45 INFO - 'TERM': 'linux', 22:19:45 INFO - 'TMOUT': '86400', 22:19:45 INFO - 'USER': 'cltbld', 22:19:45 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:45 INFO - '_': '/tools/buildbot/bin/python'} 22:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:45 INFO - Downloading/unpacking psutil>=3.1.1 22:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:19:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:19:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:19:47 INFO - Installing collected packages: psutil 22:19:47 INFO - Running setup.py install for psutil 22:19:47 INFO - building 'psutil._psutil_linux' extension 22:19:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:19:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:19:47 INFO - building 'psutil._psutil_posix' extension 22:19:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:19:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:19:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:19:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:19:48 INFO - Successfully installed psutil 22:19:48 INFO - Cleaning up... 22:19:48 INFO - Return code: 0 22:19:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:19:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:19:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:19:48 INFO - Using env: {'DISPLAY': ':0', 22:19:48 INFO - 'HOME': '/home/cltbld', 22:19:48 INFO - 'LANG': 'en_US.UTF-8', 22:19:48 INFO - 'LANGUAGE': 'en_US:en', 22:19:48 INFO - 'LOGNAME': 'cltbld', 22:19:48 INFO - 'MAIL': '/var/mail/cltbld', 22:19:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:48 INFO - 'MOZ_NO_REMOTE': '1', 22:19:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:48 INFO - 'NO_EM_RESTART': '1', 22:19:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:48 INFO - 'PWD': '/builds/slave/test', 22:19:48 INFO - 'SHELL': '/bin/bash', 22:19:48 INFO - 'SHLVL': '1', 22:19:48 INFO - 'TERM': 'linux', 22:19:48 INFO - 'TMOUT': '86400', 22:19:48 INFO - 'USER': 'cltbld', 22:19:48 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:19:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:48 INFO - '_': '/tools/buildbot/bin/python'} 22:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:19:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:19:50 INFO - Installing collected packages: mozsystemmonitor 22:19:50 INFO - Running setup.py install for mozsystemmonitor 22:19:50 INFO - Successfully installed mozsystemmonitor 22:19:50 INFO - Cleaning up... 22:19:50 INFO - Return code: 0 22:19:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:50 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:19:50 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 22:19:50 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 22:19:50 INFO - Using env: {'DISPLAY': ':0', 22:19:50 INFO - 'HOME': '/home/cltbld', 22:19:50 INFO - 'LANG': 'en_US.UTF-8', 22:19:50 INFO - 'LANGUAGE': 'en_US:en', 22:19:50 INFO - 'LOGNAME': 'cltbld', 22:19:50 INFO - 'MAIL': '/var/mail/cltbld', 22:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:50 INFO - 'MOZ_NO_REMOTE': '1', 22:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:50 INFO - 'NO_EM_RESTART': '1', 22:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:50 INFO - 'PWD': '/builds/slave/test', 22:19:50 INFO - 'SHELL': '/bin/bash', 22:19:50 INFO - 'SHLVL': '1', 22:19:50 INFO - 'TERM': 'linux', 22:19:50 INFO - 'TMOUT': '86400', 22:19:50 INFO - 'USER': 'cltbld', 22:19:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:50 INFO - '_': '/tools/buildbot/bin/python'} 22:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:50 INFO - Downloading/unpacking jsonschema==2.5.1 22:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:19:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:52 INFO - Downloading functools32-3.2.3-2.tar.gz 22:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:19:52 INFO - warning: no files found matching '*.txt' 22:19:52 INFO - no previously-included directories found matching 'build' 22:19:52 INFO - no previously-included directories found matching 'dist' 22:19:52 INFO - no previously-included directories found matching '.git*' 22:19:52 INFO - Installing collected packages: jsonschema, functools32 22:19:52 INFO - Running setup.py install for functools32 22:19:52 INFO - warning: no files found matching '*.txt' 22:19:52 INFO - no previously-included directories found matching 'build' 22:19:52 INFO - no previously-included directories found matching 'dist' 22:19:52 INFO - no previously-included directories found matching '.git*' 22:19:52 INFO - Successfully installed jsonschema functools32 22:19:52 INFO - Cleaning up... 22:19:52 INFO - Return code: 0 22:19:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:52 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:19:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:19:52 INFO - Using env: {'DISPLAY': ':0', 22:19:52 INFO - 'HOME': '/home/cltbld', 22:19:52 INFO - 'LANG': 'en_US.UTF-8', 22:19:52 INFO - 'LANGUAGE': 'en_US:en', 22:19:52 INFO - 'LOGNAME': 'cltbld', 22:19:52 INFO - 'MAIL': '/var/mail/cltbld', 22:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:52 INFO - 'MOZ_NO_REMOTE': '1', 22:19:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:52 INFO - 'NO_EM_RESTART': '1', 22:19:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:52 INFO - 'PWD': '/builds/slave/test', 22:19:52 INFO - 'SHELL': '/bin/bash', 22:19:52 INFO - 'SHLVL': '1', 22:19:52 INFO - 'TERM': 'linux', 22:19:52 INFO - 'TMOUT': '86400', 22:19:52 INFO - 'USER': 'cltbld', 22:19:52 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:52 INFO - '_': '/tools/buildbot/bin/python'} 22:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:53 INFO - Downloading/unpacking blobuploader==1.2.4 22:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:55 INFO - Downloading blobuploader-1.2.4.tar.gz 22:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:19:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:19:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:19:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:19:55 INFO - Downloading docopt-0.6.1.tar.gz 22:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:19:55 INFO - Installing collected packages: blobuploader, requests, docopt 22:19:55 INFO - Running setup.py install for blobuploader 22:19:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:19:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:19:55 INFO - Running setup.py install for requests 22:19:56 INFO - Running setup.py install for docopt 22:19:56 INFO - Successfully installed blobuploader requests docopt 22:19:56 INFO - Cleaning up... 22:19:56 INFO - Return code: 0 22:19:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:56 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:19:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:19:56 INFO - Using env: {'DISPLAY': ':0', 22:19:56 INFO - 'HOME': '/home/cltbld', 22:19:56 INFO - 'LANG': 'en_US.UTF-8', 22:19:56 INFO - 'LANGUAGE': 'en_US:en', 22:19:56 INFO - 'LOGNAME': 'cltbld', 22:19:56 INFO - 'MAIL': '/var/mail/cltbld', 22:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:56 INFO - 'MOZ_NO_REMOTE': '1', 22:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:56 INFO - 'NO_EM_RESTART': '1', 22:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:56 INFO - 'PWD': '/builds/slave/test', 22:19:56 INFO - 'SHELL': '/bin/bash', 22:19:56 INFO - 'SHLVL': '1', 22:19:56 INFO - 'TERM': 'linux', 22:19:56 INFO - 'TMOUT': '86400', 22:19:56 INFO - 'USER': 'cltbld', 22:19:56 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:56 INFO - '_': '/tools/buildbot/bin/python'} 22:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:19:56 INFO - Running setup.py (path:/tmp/pip-e4qmZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:19:56 INFO - Running setup.py (path:/tmp/pip-GYVxnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:19:56 INFO - Running setup.py (path:/tmp/pip-38cDqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:19:56 INFO - Running setup.py (path:/tmp/pip-DAjvsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:19:56 INFO - Running setup.py (path:/tmp/pip-UnCFMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:19:56 INFO - Running setup.py (path:/tmp/pip-hrqXU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:19:57 INFO - Running setup.py (path:/tmp/pip-tLRWow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:19:57 INFO - Running setup.py (path:/tmp/pip-AkfDlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:19:57 INFO - Running setup.py (path:/tmp/pip-gKZUJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:19:57 INFO - Running setup.py (path:/tmp/pip-pU8svG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:19:57 INFO - Running setup.py (path:/tmp/pip-W2pxDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:19:57 INFO - Running setup.py (path:/tmp/pip-8yyTjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:19:57 INFO - Running setup.py (path:/tmp/pip-Frnqyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:19:57 INFO - Running setup.py (path:/tmp/pip-aiAoe6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:19:57 INFO - Running setup.py (path:/tmp/pip-XkPOd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:19:57 INFO - Running setup.py (path:/tmp/pip-VFNrmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:19:57 INFO - Running setup.py (path:/tmp/pip-y5xgeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:19:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:19:57 INFO - Running setup.py install for manifestparser 22:19:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:19:57 INFO - Running setup.py install for mozcrash 22:19:58 INFO - Running setup.py install for mozdebug 22:19:58 INFO - Running setup.py install for mozdevice 22:19:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Running setup.py install for mozfile 22:19:58 INFO - Running setup.py install for mozhttpd 22:19:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Running setup.py install for mozinfo 22:19:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Running setup.py install for mozInstall 22:19:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Running setup.py install for mozleak 22:19:58 INFO - Running setup.py install for mozlog 22:19:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Running setup.py install for moznetwork 22:19:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:19:58 INFO - Running setup.py install for mozprocess 22:19:59 INFO - Running setup.py install for mozprofile 22:19:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:19:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:19:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:19:59 INFO - Running setup.py install for mozrunner 22:19:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:19:59 INFO - Running setup.py install for mozscreenshot 22:19:59 INFO - Running setup.py install for moztest 22:19:59 INFO - Running setup.py install for mozversion 22:19:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:19:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:19:59 INFO - Cleaning up... 22:19:59 INFO - Return code: 0 22:19:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:19:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:19:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:19:59 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:19:59 INFO - Using env: {'DISPLAY': ':0', 22:19:59 INFO - 'HOME': '/home/cltbld', 22:19:59 INFO - 'LANG': 'en_US.UTF-8', 22:19:59 INFO - 'LANGUAGE': 'en_US:en', 22:19:59 INFO - 'LOGNAME': 'cltbld', 22:19:59 INFO - 'MAIL': '/var/mail/cltbld', 22:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:19:59 INFO - 'MOZ_NO_REMOTE': '1', 22:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:19:59 INFO - 'NO_EM_RESTART': '1', 22:19:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:19:59 INFO - 'PWD': '/builds/slave/test', 22:19:59 INFO - 'SHELL': '/bin/bash', 22:19:59 INFO - 'SHLVL': '1', 22:19:59 INFO - 'TERM': 'linux', 22:19:59 INFO - 'TMOUT': '86400', 22:19:59 INFO - 'USER': 'cltbld', 22:19:59 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:19:59 INFO - '_': '/tools/buildbot/bin/python'} 22:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:19:59 INFO - Running setup.py (path:/tmp/pip-AfsZeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:19:59 INFO - Running setup.py (path:/tmp/pip-RCKj2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:19:59 INFO - Running setup.py (path:/tmp/pip-_x4YLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:20:00 INFO - Running setup.py (path:/tmp/pip-TyX_hV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:20:00 INFO - Running setup.py (path:/tmp/pip-qj8YHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:20:00 INFO - Running setup.py (path:/tmp/pip-jZc4Cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:20:00 INFO - Running setup.py (path:/tmp/pip-ChZID3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:20:00 INFO - Running setup.py (path:/tmp/pip-uu125Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:20:00 INFO - Running setup.py (path:/tmp/pip-ltZTw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:20:00 INFO - Running setup.py (path:/tmp/pip-rxeC8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:20:00 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)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:20:00 INFO - Running setup.py (path:/tmp/pip-UoedZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:20:00 INFO - Running setup.py (path:/tmp/pip-_9ck9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:20:00 INFO - Running setup.py (path:/tmp/pip-Qmjzlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:20:00 INFO - Running setup.py (path:/tmp/pip-sNxUDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:00 INFO - Running setup.py (path:/tmp/pip-F3AW2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:20:00 INFO - Running setup.py (path:/tmp/pip-WwmFB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:20:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:20:01 INFO - Running setup.py (path:/tmp/pip-_mgRbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:20:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:02 INFO - Downloading blessings-1.6.tar.gz 22:20:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:20:03 INFO - Installing collected packages: blessings 22:20:03 INFO - Running setup.py install for blessings 22:20:03 INFO - Successfully installed blessings 22:20:03 INFO - Cleaning up... 22:20:03 INFO - Return code: 0 22:20:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:20:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:03 INFO - Reading from file tmpfile_stdout 22:20:03 INFO - Current package versions: 22:20:03 INFO - argparse == 1.2.1 22:20:03 INFO - blessings == 1.6 22:20:03 INFO - blobuploader == 1.2.4 22:20:03 INFO - docopt == 0.6.1 22:20:03 INFO - functools32 == 3.2.3-2 22:20:03 INFO - jsonschema == 2.5.1 22:20:03 INFO - manifestparser == 1.1 22:20:03 INFO - mozInstall == 1.12 22:20:03 INFO - mozcrash == 0.17 22:20:03 INFO - mozdebug == 0.1 22:20:03 INFO - mozdevice == 0.48 22:20:03 INFO - mozfile == 1.2 22:20:03 INFO - mozhttpd == 0.7 22:20:03 INFO - mozinfo == 0.9 22:20:03 INFO - mozleak == 0.1 22:20:03 INFO - mozlog == 3.2 22:20:03 INFO - moznetwork == 0.27 22:20:03 INFO - mozprocess == 0.22 22:20:03 INFO - mozprofile == 0.28 22:20:03 INFO - mozrunner == 6.11 22:20:03 INFO - mozscreenshot == 0.1 22:20:03 INFO - mozsystemmonitor == 0.3 22:20:03 INFO - moztest == 0.7 22:20:03 INFO - mozversion == 1.4 22:20:03 INFO - psutil == 3.1.1 22:20:03 INFO - requests == 1.2.3 22:20:03 INFO - wsgiref == 0.1.2 22:20:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:03 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:20:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:20:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:20:03 INFO - Using env: {'DISPLAY': ':0', 22:20:03 INFO - 'HOME': '/home/cltbld', 22:20:03 INFO - 'LANG': 'en_US.UTF-8', 22:20:03 INFO - 'LANGUAGE': 'en_US:en', 22:20:03 INFO - 'LOGNAME': 'cltbld', 22:20:03 INFO - 'MAIL': '/var/mail/cltbld', 22:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:03 INFO - 'MOZ_NO_REMOTE': '1', 22:20:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:03 INFO - 'NO_EM_RESTART': '1', 22:20:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:03 INFO - 'PWD': '/builds/slave/test', 22:20:03 INFO - 'SHELL': '/bin/bash', 22:20:03 INFO - 'SHLVL': '1', 22:20:03 INFO - 'TERM': 'linux', 22:20:03 INFO - 'TMOUT': '86400', 22:20:03 INFO - 'USER': 'cltbld', 22:20:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:03 INFO - '_': '/tools/buildbot/bin/python'} 22:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:20:03 INFO - Cleaning up... 22:20:03 INFO - Return code: 0 22:20:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:03 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:20:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:20:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:20:03 INFO - Using env: {'DISPLAY': ':0', 22:20:03 INFO - 'HOME': '/home/cltbld', 22:20:03 INFO - 'LANG': 'en_US.UTF-8', 22:20:03 INFO - 'LANGUAGE': 'en_US:en', 22:20:03 INFO - 'LOGNAME': 'cltbld', 22:20:03 INFO - 'MAIL': '/var/mail/cltbld', 22:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:03 INFO - 'MOZ_NO_REMOTE': '1', 22:20:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:03 INFO - 'NO_EM_RESTART': '1', 22:20:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:03 INFO - 'PWD': '/builds/slave/test', 22:20:03 INFO - 'SHELL': '/bin/bash', 22:20:03 INFO - 'SHLVL': '1', 22:20:03 INFO - 'TERM': 'linux', 22:20:03 INFO - 'TMOUT': '86400', 22:20:03 INFO - 'USER': 'cltbld', 22:20:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:03 INFO - '_': '/tools/buildbot/bin/python'} 22:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:20:03 INFO - Cleaning up... 22:20:03 INFO - Return code: 0 22:20:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:20:03 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 0x7fe0fa2b7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1433350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:20:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:20:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:20:03 INFO - Using env: {'DISPLAY': ':0', 22:20:03 INFO - 'HOME': '/home/cltbld', 22:20:03 INFO - 'LANG': 'en_US.UTF-8', 22:20:03 INFO - 'LANGUAGE': 'en_US:en', 22:20:03 INFO - 'LOGNAME': 'cltbld', 22:20:03 INFO - 'MAIL': '/var/mail/cltbld', 22:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:03 INFO - 'MOZ_NO_REMOTE': '1', 22:20:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:03 INFO - 'NO_EM_RESTART': '1', 22:20:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:03 INFO - 'PWD': '/builds/slave/test', 22:20:03 INFO - 'SHELL': '/bin/bash', 22:20:03 INFO - 'SHLVL': '1', 22:20:03 INFO - 'TERM': 'linux', 22:20:03 INFO - 'TMOUT': '86400', 22:20:03 INFO - 'USER': 'cltbld', 22:20:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:03 INFO - '_': '/tools/buildbot/bin/python'} 22:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:20:04 INFO - Downloading/unpacking flake8 22:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:20:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:20:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:20:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:20:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:20:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:20:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:20:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:20:06 INFO - no previously-included directories found matching 'docs/_build' 22:20:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:20:06 INFO - Running setup.py install for pep8 22:20:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:20:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:20:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:20:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:20:06 INFO - no previously-included directories found matching 'docs/_build' 22:20:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:20:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:20:06 INFO - Cleaning up... 22:20:06 INFO - Return code: 0 22:20:06 INFO - Running post-action listener: _resource_record_post_action 22:20:06 INFO - Running post-action listener: _start_resource_monitoring 22:20:07 INFO - Starting resource monitoring. 22:20:07 INFO - ##### 22:20:07 INFO - ##### Finished create-virtualenv step (success) 22:20:07 INFO - ##### 22:20:07 INFO - ##### 22:20:07 INFO - ##### Running install step. 22:20:07 INFO - ##### 22:20:07 INFO - Running pre-action listener: _resource_record_pre_action 22:20:07 INFO - Running main action method: install 22:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:20:07 INFO - Reading from file tmpfile_stdout 22:20:07 INFO - Detecting whether we're running mozinstall >=1.0... 22:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:20:07 INFO - Reading from file tmpfile_stdout 22:20:07 INFO - Output received: 22:20:07 INFO - Usage: mozinstall [options] installer 22:20:07 INFO - Options: 22:20:07 INFO - -h, --help show this help message and exit 22:20:07 INFO - -d DEST, --destination=DEST 22:20:07 INFO - Directory to install application into. [default: 22:20:07 INFO - "/builds/slave/test"] 22:20:07 INFO - --app=APP Application being installed. [default: firefox] 22:20:07 INFO - mkdir: /builds/slave/test/build/application 22:20:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:20:24 INFO - Reading from file tmpfile_stdout 22:20:24 INFO - Output received: 22:20:24 INFO - /builds/slave/test/build/application/firefox/firefox 22:20:24 INFO - Running post-action listener: _resource_record_post_action 22:20:24 INFO - ##### 22:20:24 INFO - ##### Finished install step (success) 22:20:24 INFO - ##### 22:20:24 INFO - ##### 22:20:24 INFO - ##### Running run-tests step. 22:20:24 INFO - ##### 22:20:24 INFO - Running pre-action listener: _resource_record_pre_action 22:20:24 INFO - Running main action method: run_tests 22:20:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:20:24 INFO - Python 2.7.3 22:20:24 INFO - Return code: 0 22:20:24 INFO - grabbing minidump binary from tooltool 22:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:24 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 0x14c21c0>, '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 0x14c30c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14c3550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:20:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:20:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:20:24 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 22:20:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:20:24 INFO - Return code: 0 22:20:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:20:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:20:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:20:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:20:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:20:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:20:25 INFO - Return code: 0 22:20:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:20:25 INFO - Using env: {'DISPLAY': ':0', 22:20:25 INFO - 'HOME': '/home/cltbld', 22:20:25 INFO - 'LANG': 'en_US.UTF-8', 22:20:25 INFO - 'LANGUAGE': 'en_US:en', 22:20:25 INFO - 'LOGNAME': 'cltbld', 22:20:25 INFO - 'MAIL': '/var/mail/cltbld', 22:20:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:20:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:20:25 INFO - 'MOZ_NO_REMOTE': '1', 22:20:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:20:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:25 INFO - 'NO_EM_RESTART': '1', 22:20:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:25 INFO - 'PWD': '/builds/slave/test', 22:20:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:20:25 INFO - 'SHELL': '/bin/bash', 22:20:25 INFO - 'SHLVL': '1', 22:20:25 INFO - 'TERM': 'linux', 22:20:25 INFO - 'TMOUT': '86400', 22:20:25 INFO - 'USER': 'cltbld', 22:20:25 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809', 22:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:20:25 INFO - '_': '/tools/buildbot/bin/python'} 22:20:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:20:25 INFO - mozversion application_buildid: 20160616193134 22:20:25 INFO - mozversion application_changeset: 5f95858f8ddf21ea2271a12810332efd09eff138 22:20:25 INFO - mozversion application_display_name: Nightly 22:20:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:20:25 INFO - mozversion application_name: Firefox 22:20:25 INFO - mozversion application_remotingname: firefox 22:20:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:20:25 INFO - mozversion application_vendor: Mozilla 22:20:25 INFO - mozversion application_version: 50.0a1 22:20:25 INFO - mozversion platform_buildid: 20160616193134 22:20:25 INFO - mozversion platform_changeset: 5f95858f8ddf21ea2271a12810332efd09eff138 22:20:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:20:25 INFO - mozversion platform_version: 50.0a1 22:20:25 INFO - using testdate: 1466140825 22:20:25 INFO - actual date: 1466140825 22:20:25 INFO - starting webserver on 'localhost:43239' 22:20:25 INFO - SUITE-START | Running 2 tests 22:20:25 INFO - TEST-START | dromaeo_css 22:20:25 INFO - operating with platform_type : linux_ 22:20:25 INFO - Initialising browser for dromaeo_css test... 22:20:25 INFO - TEST-INFO | started process 27796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5mxiWo/profile http://localhost:43239/getInfo.html) 22:20:29 INFO - PROCESS | 27796 | __metrics Screen width/height:1600/1200 22:20:29 INFO - PROCESS | 27796 | colorDepth:24 22:20:29 INFO - PROCESS | 27796 | Browser inner width/height: 1024/697 22:20:29 INFO - PROCESS | 27796 | __metrics 22:20:30 ERROR - PROCESS | 27796 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 22:20:31 INFO - TEST-INFO | 27796: exit 0 22:20:31 INFO - Browser initialized. 22:20:31 INFO - Running cycle 1/1 for dromaeo_css test... 22:20:31 INFO - TEST-INFO | started process 27918 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5mxiWo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:20:43 INFO - PROCESS | 27918 | RSS: Main: 184807424 22:20:43 INFO - PROCESS | 27918 | 22:21:32 INFO - PROCESS | 27918 | 1466140892920 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:23:41 INFO - PROCESS | 27918 | Cycle 1(1): loaded http://localhost:43239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43239/tests/dromaeo/cssquery-ext.html) 22:23:42 INFO - PROCESS | 27918 | RSS: Main: 189235200 22:23:42 INFO - PROCESS | 27918 | 22:26:44 INFO - PROCESS | 27918 | Cycle 1(1): loaded http://localhost:43239/tests/dromaeo/cssquery-ext.html (next: http://localhost:43239/tests/dromaeo/cssquery-jquery.html) 22:26:44 INFO - PROCESS | 27918 | RSS: Main: 187932672 22:26:44 INFO - PROCESS | 27918 | 22:29:46 INFO - PROCESS | 27918 | Cycle 1(1): loaded http://localhost:43239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43239/tests/dromaeo/cssquery-mootools.html) 22:29:47 INFO - PROCESS | 27918 | RSS: Main: 188837888 22:29:47 INFO - PROCESS | 27918 | 22:32:49 INFO - PROCESS | 27918 | Cycle 1(1): loaded http://localhost:43239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43239/tests/dromaeo/cssquery-prototype.html) 22:32:49 INFO - PROCESS | 27918 | RSS: Main: 186777600 22:32:49 INFO - PROCESS | 27918 | 22:35:52 INFO - PROCESS | 27918 | Cycle 1(1): loaded http://localhost:43239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43239/tests/dromaeo/cssquery-yui.html) 22:35:52 INFO - PROCESS | 27918 | RSS: Main: 189014016 22:35:52 INFO - PROCESS | 27918 | 22:38:55 INFO - PROCESS | 27918 | Cycle 1(1): loaded http://localhost:43239/tests/dromaeo/cssquery-yui.html (next: http://localhost:43239/tests/dromaeo/cssquery-dojo.html) 22:38:56 INFO - PROCESS | 27918 | RSS: Main: 190017536 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - PROCESS | 27918 | __start_tp_report 22:38:56 INFO - PROCESS | 27918 | _x_x_mozilla_page_load 22:38:56 INFO - PROCESS | 27918 | _x_x_mozilla_page_load_details 22:38:56 INFO - PROCESS | 27918 | |i|pagename|runs| 22:38:56 INFO - PROCESS | 27918 | |0;dojo.html;3085.8765649374027;3166.6674999125075;3180.268538236207;3182.8408579571014;3204.2149673330023;5424.0236757383655;5426.131818908975;5470.452954704528;5544.16837474379;5572.108462645978;5425.9186112208345;5428;5469.206964990074;5475.4524547545225;5577.776888924438;4903.411590609123;4929.4824043475455;5025.296458495814;5060.7722652480725;5126.051680439112;602.1233478596155;4988.675736077162;4994.1260279451135;5021.046001259767;5040.042391945519;4901.240307752304;4930.161872481669;4940.061388336205;4953.4303555091265;5003.624728145403;4876.244182151737;4968.627352948543;4976.726280054596;4980.601551875841;5021.39743230811;4809.158397280462;4864.878378040542;4872.878178045542;4912.754362281871;4916.50834916509;5365.973170134163;5369.785208591652;5413.83758487246;5419.458054194589;5476.288082549283;5648.237487939116;5793.9130913035915;5795.652260864362;5797.246357973479;5821.18503409523;5897.705114744289;5934.287885453773;5949.9702501487645;5955.3151387590115;6018.217631707894;5543;5550.111982082847;5553.028220061514;5557.027520183951;5557.3331200256225;5893;5928.762849486058;5966.820995370059;6034.577579569388;6047.395260474005;5813.970930145407;5820.679862607597;5822.155787410887;5823.737931793079;5863.3257175424515;5873.618214816023;5894.557908156906;5902.3507414184405;5909.497692696083;5915.58590898633;5656.349519805192;5746.770129194785;5747.597668163272;5862.824115276549;5896.528277737771;5734.139879018097;5763.049096898964;5789.884202316014;5872.677002764804;5909.202257695156;4469.307257375112;4477.328400739915;4485.304777759453;4486.551344865487;4512.187806194851;3950.585188555218;3955.841766329372;3997;4008.7394319369146;4057.2696914555663;5706.201131841545;5712.11462223356;5715.514181294637;5718.914216286676;5725;5941.58408911372;6014.6391216527145;6018.608790428608;6022.09668549721;6023.1266466363;5489.945100549024;5525.640833345819;5540.307461567304;5593.692346920959;5718.771249149988;5087.745612719349;5093.057784309841;5093.617978651616;5102.515261050195;5104.795808167706;6139.907901381483;6156.29202641693;6162.722677479434;6166.044263139131;6168.537359698041;5792.189093526994;5823.70881455934;5832.795852145239;5853.502452291603;5868.67722275279;6063.423974722395;6064.757409703562;6073.331933487401;6080.300765412034;6084.087386892002;5529.225908372911;5741.712914354349;5758.769649213985;5759.424057594207;5761.452661997104;5822.505087067647;5885.14665373527;5894.734736936763;5933.643981361132;5946.643201407929;5724.513416359656;5771;5793.9420605795085;5802.6808525531505;5815.302163740378;5565.220869078414;5570.91643625354;5735.483806457439;5775.653460792366;5803.912941305968;5496.587755918402;5521.199426083276;5532.280803495681;5592.608517403743;5593.300837395325;5754.971225143848;5759;5774.595778295479;5779.682117483579;5780.421957804186;5559.833205003856;5589.916151257653;5651.208830763614;5654.293213348332;5654.717264136694 22:38:56 INFO - PROCESS | 27918 | |1;ext.html;574.3882764855429;595.1845971019702;602.1509671363378;625.024961060745;628.6134027573044;42209.366859497095;43056.3541546877;43089.13821723564;43267.13465730684;47213.29180062302;44185.77907110468;44612.3308150378;45019.54980450195;45024;45167.322490162594;8779.2098711116;8920.509371984539;8967.372283940127;9015.098490150998;9042.457452552835;8740.694075707352;8838.911610883873;8862.60118294675;8930.910690893106;8931.24084452819;8891.733248002538;8932.151445612657;8972.910270897306;9005.50469724165;9007.369484136114;8850.5132217728;8885.644574217024;8958.104189581056;8974.551272436385;8974.910250897472;8767.430117042371;8867.068957759408;8880.067592902717;8909.55452227386;8910.821783564357;40818;40875.18249634991;41035.38446923269;41243.587564124275;41408;3492.5808902931813;3550.130218096557;3598.514200582914;3700.2599480104086;3763.4919285896326;31880.521792173135;32045.51931721026;32359;32661.836690816395;33308.333833323195;4379.364992076131;4472.686911916167;4529.932051019237;4639.350490931272;4649.604783593399;337969;339706;339838;340494;341309.2934535336;33995;34799.4780078299;56584.43415565792;56771;56829.147562786595;35852.10369740725;36182.095447613494;36190.819045905126;36342.454863177074;36407.635923640424;14837.62905927339;15083.321250543751;15100.244987750568;15113.16877571744;15130.167840768863;13260.336983150804;13269.071165018357;13408.061435699407;13435.596932092052;13457.663558411117;8199.877001844978;8288.378371622153;8289.461185022954;8358.707445239388;8384.8742268866;3216.244182617083;3251.512273159045;3255.4140254753897;3281.9015429537153;3318.369509793132;1010.2877471382658;1029.6626657487461;1067.215596536545;1086.3047649504324;1124.7244425115734;32244.19389515281;32359;32551.83724081412;32820.83589582085;33300.50049249263;1082.964404121421;1093.1801149138146;1095.4741723972538;1099.8185299425504;1106.0377471599759;16948.40680576174;17144.657106857794;17155.142242887807;17293.827061729222;17338.479845604652;507445;507454;507621;508142;512066;57282.42717572771;58431;58620;58793;58910;1224.6570960131178;1251.4118364368733;1293.4373547506864;1298.2859427315127;1303.2245813740808;30398.54402184013;32025.199370015936;32046.93144386851;32190.678093219703;32288.83855580707;48399;48496;48544;48577.7855553614;48756.51243487662;457309;458085;459639;461686;462368;27592.172234832986;28341.149765507067;28695.282617934718;28867.5669864948;28879.133625991253;47496.52503474921;47729.522704773895;48655.27017094817;48842.26736598883;49111.75444122757;39850.60149398469;40452.59547404488;40879.386809198484;41679.58320416757;42179.789101054295;41036.79481602573;41261.79369103135;41329.77591216663;41973.79013104915;42120;39750;39896.80051599723;40241;40599.797001014806;40999.59000409958;34908.47637285392;36617.45073823842;36778.26443471169;36955.44566831446;36960.63039369572;27257.31856703598;27736.58395124115;27849.16452506428;28052.71947280501;28075.157745267676 22:38:56 INFO - PROCESS | 27918 | |2;jquery.html;386.29693956998267;391.89222963684983;393.46881709691917;394.8243031850827;409.43497972797536;13689.794653080211;17946.551336216584;18607.813921860776;19495.22019119237;19497.805021949793;18021.4593562193;18214.3941392233;18231.0884455777;18477.722834157466;18501.259949602;5341.65279256849;5612.859678508029;5716.8856622867515;5717.028105222123;5736.168255602956;5605.103183490643;5673.092305231164;5687.573431992597;5702.857428564298;5725;5518.255035570207;5606.21512988182;5632.267805185341;5634.0703783875615;5667.064934285859;5448.37343705475;5627.521660658849;5655.57583181261;5679.261695979538;5723.627964182315;5491.36849262332;5529.668219906778;5564.165375193713;5808.2449281593545;5840.620359676607;13036.348182590833;13076.346182690828;13358.732825343439;13752;21843.453913652287;2262.8529145605485;2327.2203811723093;2482.888270027835;2758.241483592008;2980.8658610362586;14752.409903603842;15627.140507272095;15649.765253521206;15889.841101588952;15946.681066378607;1904.4572296895458;1953.8143876331726;2005.6089062632645;2078.3349328214977;2151.9462013449634;91757.54121229418;95406.52296738542;96121.5193924033;96175;96461;8912;9409.858852117224;9515.571799268911;9566.282528810367;9825.803483930282;11920.821187682192;12357.505699772088;12846;12900.935495322463;12954.740905181843;7505.4746167767735;7544.622768861535;7762.883556746653;7894.64474098667;8079.555624440713;7922.564258965697;7928.1675424080795;7936.16670249627;7979.361651068017;8141.063777665526;4017.598240175959;4037.9596204037584;4060.4315395844606;4067.9593204068174;4071.1247081877536;1859.3399343233227;1868.990655046716;1882.8399586035066;1887.169645356039;1921.7597800274966;885.9379843411028;933.8459154239474;1010.9241806864515;1038.6468600675807;1050.175612144463;18414;18539.44381668552;18662.720059199124;18904.905475472533;18978.051097445074;979.485769970771;1008.6016023670609;1028.6091285311534;1079.4548752879903;1086.3699054548342;10100.949495252476;10746.946265268623;10796;10812.918708129124;10835.404052777172;409721;425842;441775;465912;466050;87253.56373218093;87391.56304218438;87820.5608971951;90156;90438.54780726055;881.7002219245359;922.9708874604768;1034.7257976636197;1060.151878497199;1068.0761141612604;15963.361465541248;16211.675766484841;16226.837731622532;16402.26189821485;16938.23777930021;30816.383672326876;37324.813375934034;39431;39754.40368394418;41865.79067104645;54647.726761365935;59802.401975981425;59927;60236;60658;8575.713642953608;8639.308855291436;8682.175193356621;8771.956140219258;8777.736667899973;13399.263040532864;13477.123986940818;13560;13672.384742686405;13774;11605.825912611486;11695.181337306307;11807.63249192468;11965.880341196476;12003.219790713576;11800.70498237551;11829.940850295694;12002.639920802041;12015.279083255033;12291.815622765487;11237.831432528355;11426.600068997544;11942.522299107939;11954.521819127138;12039.578614748792;7528.473006889684;7890.960545197467;8529.786755331166;8602.311815054656;8629.309655227442;11505.942470287595;11771;11815.704607384883;12009.099317551394;12225.205361651464 22:38:56 INFO - PROCESS | 27918 | |3;mootools.html;1437.777144542596;1462.5173692681415;1518.119490695396;1629.9918500407496;1631.4289998500526;8001.319887809529;8712.912870871298;8716.651333946635;9333.673321433751;9336.626534938603;8754.518501482416;8916.063813299608;8921.509316987564;8977.775555611113;9025.458472491655;2242.3048854855006;2271.613825649635;2284.6915666385075;2285.3486756274465;2289.942751431211;2210.270610698474;2238.955220895581;2293.495431005176;2299.827512936528;2299.942501437469;2269.614165591845;2299.620562607168;2308.272894038381;2317.756635553268;2326.9883650581723;2219.289827255279;2220.6113930062256;2221.833362497811;2226.209695558078;2231.4309850988056;2208.1277895231456;2212.8782916939563;2213.889305534733;2214.2139540463227;2217.9223727169633;9098.272138228926;9182.081791820765;9182.632694692204;9193.126652968023;9195.586198621011;5145.4597267287145;5255.290535777659;5266.7629956652045;5270.6837589744355;5280.735963201863;3081.9229519261976;3218.1150183699438;3359.9160020999425;3394.745394095428;3404.3361544498584;1084.3006260961715;1092.355510248949;1099.8735145458295;1113.0817075912405;1133.489929531714;12666.936665316707;12693.809592856114;12694.238345699288;12736.490540378281;12736.745265094738;4375.693701440932;4420.270655341897;4431.82272709088;4454.554544545585;4506.954930450719;10373.84439233412;10411.219158563137;10522.894771052344;10548.736281592868;10597.62908298206;5218.373795144607;5256.316678831728;5258.316418865523;5263.184206448007;5282.181261904411;4212.873613791591;4619.8845028873875;4779.330893674888;4799.688020278671;4877.780499877514;3906.4726261954843;3928.901777455529;4015.859444919414;4021.4369988201674;4039.1921615676983;1273.2106094221642;1275.5663074554695;1277.5975567696332;1278.4374875054946;1299.4802079168408;891.3047822698305;914.2492127575752;949.0746522140995;955.9761005974767;995.328153496387;5960.612560183661;6041.335453100155;6161.983272760034;6194.287656919422;6203.689815509207;419.6831392298795;420.82956402656976;422.0882892951272;427.9850205242802;438.96927215095275;4178.916421671611;4261.062566235485;4279.700420970565;4297.656187504992;4297.88255053682;11009;11430.314181149157;11458;11460.426978651034;11474.196806223485;10363.585456581652;11332.546698132148;11380.260283081574;11415.714607134889;11416.828747568794;8316.87722157501;8360.623771930277;8428.536430496382;8446.704365347183;8461.069282379056;5717.256756621613;5997.16039754426;6000.489958353589;6098.298695650057;6136.662483563447;9868.210543156674;10104.242181836538;10316.948415257875;10340.482975851028;10391.948040259751;10372.429516376671;10386;10443.582256709646;10494.47527623631;10601.840972385266;7086.893696594452;7623.542587444771;7862.253085956825;7883.7240696575345;8014.19858014194;8667.263282620796;8798.076201998698;8809.251213646912;8892.64429422816;8892.911070889468;7585.16563178061;7615.809604759926;7716.112647045662;7733.419993500397;7870.606469676379;7552.509086909334;7577.583232922242;7600.657970391235;7684.884726728992;7694.692212311445;7036.401905837856;7050.964745176241;7084.929150708427;7087.82280442993;7127.964360178166;4907.754612269301;4960.72716000609;5004.549590536869;5027.044861476308;5079.415867175322;5196.662216955886;5228.503292187236;5252.238425428368;5329.307190065422;5371.274877891435 22:38:56 INFO - PROCESS | 27918 | |4;prototype.html;315.95576619273294;321.92273854274976;325.9266665000375;328.0519299225238;331.71638249296865;5571.4428557144265;5577.219189313494;5644.322681278242;5693.886122277557;5744.224529688491;5489.64317319374;5518.806841760539;5525.922445123203;5546.611737178399;5556.194351818984;4054.2499637567;4220.767857767822;4263.680223983198;4272.658187345005;4284.400183974246;4253.468316460442;4289.528151903288;4292.849750258742;4330.610245077943;4333.245035760507;4262;4288.571142885702;4294.561321957132;4296.312589985603;4304.397384366192;4007.9599204008023;4195.160967806451;4198.916021679549;4200.663946884242;4250.766207858566;3681.1901381695984;3686.944695829565;3691.8523259069607;3695.6489133532273;3714.220013797079;5483.533899618537;5510.9448905510835;5514.393416724157;5546.77812887484;5557;4671.392718946515;4903.975480122613;4920.261960705887;4926.285688575173;4926.975365123151;5807;5813.563982701315;5824.737886795062;5833.212516310286;5838.299404071538;4105.774182419966;4118.75287482749;4127.442795222637;4139.8965025874295;4140.0891803803115;5925.466707996301;5928.458600763833;5962.522998160137;5965.910511342333;6143.508519318444;4152.1695660867945;4161.625453709181;4175.5824417558;4227.027783609787;4234.7247428917635;4211.431456753299;4249.086446414036;4252.277112890816;4274.658027357804;4289.0992891492515;4677.579017888406;4850.660453768202;4861.763588749215;4891.437484689306;4904.852854414374;5808.332041815161;5815.127730840408;5888.32182827892;5889.469947704727;5907.556933230025;1436.9712605747827;1448.7971683964254;1450.2531196433845;1452.8038714773581;1456.3519233940794;923.1922068190333;925.833349997007;934.8317302885546;952.8094381123803;988.9456079915674;5568.387477377485;5576.9163462548095;5583.883223355345;5599.552035837123;5599.580031497654;5864.882702346014;5874.416605870416;5936.436038576328;5979.1629171915965;6010.136857574166;5236.512656488214;5438.510534051954;5513.338399392099;5542.972285138549;5627.549796016309;3126.5622812806228;3146.4179126861604;3150.3069324748517;3151.306712523241;3155.984220078931;5864.589478736448;6072;6078.726457309432;6161.4146656067605;6177.536684748662;5807.186993820868;5919.934411805916;6028.788992385245;6049.455549000611;6052.425019623129;5999.490043346277;6173.536984726164;6179.938200617937;6200.255969283625;6251.874962500725;5684.635687435069;5917.230760001173;5919.171316015675;5919.319278283053;5957.791477298274;5805.796797112081;5826.446487583673;5865.472107510344;5885.440358305138;5936.168936349001;5924.822255332347;6055.819115272479;6081.300650425258;6087.139044649008;6187.597806142587;4120.434234990685;4121.278776214211;4131.091159944807;4149.190907773076;4170.436991006175;5924.407559244041;5925.200097986716;5943.197668314722;5971.970140149271;5984.760609575568;5490.917636235539;5671.376148623731;5715.257016587817;5731.885362292647;5745.597808153389;5686.71566421669;5709.857253568694;5720.427957204246;5727.4845263926445;5745.827625171251;5452.727363631723;5504.256925315191;5616.511624419614;5635.239242702182;5636.5208957237455;5714.200011998407;5862.589618726819;5888.440598143169;5889.557058520519;5893.499052580579;5513.779448822002;5674.829755107354;5706;5721.542276617777;5726.198332233574 22:38:56 INFO - PROCESS | 27918 | |5;yui.html;772.4399810137651;775.1434664695507;780.4192899074673;783.8667426537488;841.9115992820754;13758.44966201352;14338;15085;15140.242987850605;15196;13793.44826206953;13870.445182192725;13886.722265554683;13914.234717090556;13970.301484925763;766.2643861892576;770.5761830992959;778.097407007871;780.8438312337527;796.749024057423;798.1659166171336;798.3573223555036;800.7277525641281;802.5545822068749;803.9879401808978;840.9243168114868;841.9957900210521;842.9578521073953;843.4138273899642;844.0968164064453;830.9542975136366;834.0741776627938;836.1972506393839;837.1753822484878;838.4717627894448;604.694629212245;608.5253502268238;608.9360617135181;609.2780055634088;610.261583483985;15485.070895746292;15509.069455832687;15519.844801551953;15536.300866461037;15553.766693499607;14495.855041449557;14569.562913112517;14571.344289506998;14720.263986800725;14787.778183327258;12054.276743395425;12056.758864822743;12229.205101668364;12273.57042503517;12794.680132996657;3829.751066180689;3866.9226615467533;3980;4045.1302969861326;4052.0275133967634;77063.07404779278;77702.22297777007;78662;78857.60571197107;79975.6001219996;11003.22977391575;11170.106391488662;11248.26886252391;11269.54921803135;11601.477933493014;20386.79613203849;20443;20450.079746411448;20453.48866278355;20490.897545512176;7964.681412743541;8250.711225107212;8427.831443371098;8435.283000944866;8548.700795472252;4538.931916021263;4569.81720731167;4631.698939568917;4650.465196502378;4737.786799594027;3406.369821583015;3500.334936362083;3516.3494753470177;3518.6980817097324;3530.3292374448774;577.8150991682621;584.9122631605293;589.3546566509665;598.4853026397295;603.4177019176516;625.4246093593905;664.8304682305981;705.9788206353818;733.8532293541313;753.3634079203065;9796.746748247942;9808.21534277256;10010.699679009642;10159.390436573829;10166.898331016742;601.6209787833636;629.2763322179438;653.4119292636665;704.7531130731653;738.8596166728305;4674.293371715003;4716.410448693913;4762.261849413347;4778.211595086772;4837.298591704253;79329.60335198288;114288.42855785834;114555;117387.41306293414;117938;96712;97625.51187244018;98358;98497.50751246198;98866.5056674712;68677;84403.5779821097;84420.57789711012;84911;87694.56152719195;8946.86579701292;9090.909090909006;9194.264458843143;9201.907980920105;9348.392354496937;35044.474332884514;48209.5179048205;48331;48554.572271387;49850.501494986034;60469.395306048136;63793.362066378744;66127;71161.28838711546;72001;10802.297850639683;11921.82117268259;13117.672058198621;13147.211167329991;13402.93298533501;15056.30708982303;15134.50129681712;15215;15382.692346153239;15512.612184695472;13428;13515.459381624625;13941.721165576835;13984.930075349557;14286.642833929234;13809.240491772647;13859.30703464843;14146.221957792008;14518.346674399467;14703.264836757906;11739.201937596326;13370.799438008244;13379.665508362368;13789;13900.16599004063;11963.342016189194;12050.457729402373;12058.15592908488;12129.393530323625;12466.5013399463;10983.725406864893;11551.71120721955;11739.47172377228;12000.39998000079;12445 22:38:56 INFO - PROCESS | 27918 | __end_tp_report 22:38:56 INFO - PROCESS | 27918 | __start_cc_report 22:38:56 INFO - PROCESS | 27918 | _x_x_mozilla_cycle_collect,390 22:38:56 INFO - PROCESS | 27918 | __end_cc_report 22:38:56 INFO - PROCESS | 27918 | __startTimestamp1466141936157__endTimestamp 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - PROCESS | 27918 | ------- Summary: start ------- 22:38:56 INFO - PROCESS | 27918 | Number of tests: 6 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - PROCESS | 27918 | [#0] dojo.html Cycles:165 Average:5423.78 Median:5656.35 stddev:731.75 (12.9%) stddev-sans-first:710.64 22:38:56 INFO - PROCESS | 27918 | Values: 3085.9 3166.7 3180.3 3182.8 3204.2 5424.0 5426.1 5470.5 5544.2 5572.1 5425.9 5428.0 5469.2 5475.5 5577.8 4903.4 4929.5 5025.3 5060.8 5126.1 602.1 4988.7 4994.1 5021.0 5040.0 4901.2 4930.2 4940.1 4953.4 5003.6 4876.2 4968.6 4976.7 4980.6 5021.4 4809.2 4864.9 4872.9 4912.8 4916.5 5366.0 5369.8 5413.8 5419.5 5476.3 5648.2 5793.9 5795.7 5797.2 5821.2 5897.7 5934.3 5950.0 5955.3 6018.2 5543.0 5550.1 5553.0 5557.0 5557.3 5893.0 5928.8 5966.8 6034.6 6047.4 5814.0 5820.7 5822.2 5823.7 5863.3 5873.6 5894.6 5902.4 5909.5 5915.6 5656.3 5746.8 5747.6 5862.8 5896.5 5734.1 5763.0 5789.9 5872.7 5909.2 4469.3 4477.3 4485.3 4486.6 4512.2 3950.6 3955.8 3997.0 4008.7 4057.3 5706.2 5712.1 5715.5 5718.9 5725.0 5941.6 6014.6 6018.6 6022.1 6023.1 5489.9 5525.6 5540.3 5593.7 5718.8 5087.7 5093.1 5093.6 5102.5 5104.8 6139.9 6156.3 6162.7 6166.0 6168.5 5792.2 5823.7 5832.8 5853.5 5868.7 6063.4 6064.8 6073.3 6080.3 6084.1 5529.2 5741.7 5758.8 5759.4 5761.5 5822.5 5885.1 5894.7 5933.6 5946.6 5724.5 5771.0 5793.9 5802.7 5815.3 5565.2 5570.9 5735.5 5775.7 5803.9 5496.6 5521.2 5532.3 5592.6 5593.3 5755.0 5759.0 5774.6 5779.7 5780.4 5559.8 5589.9 5651.2 5654.3 5654.7 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - PROCESS | 27918 | [#1] ext.html Cycles:180 Average:58432.56 Median:40939.49 stddev:117275.48 (286.5%) stddev-sans-first:117524.01 22:38:56 INFO - PROCESS | 27918 | Values: 574.4 595.2 602.2 625.0 628.6 42209.4 43056.4 43089.1 43267.1 47213.3 44185.8 44612.3 45019.5 45024.0 45167.3 8779.2 8920.5 8967.4 9015.1 9042.5 8740.7 8838.9 8862.6 8930.9 8931.2 8891.7 8932.2 8972.9 9005.5 9007.4 8850.5 8885.6 8958.1 8974.6 8974.9 8767.4 8867.1 8880.1 8909.6 8910.8 40818.0 40875.2 41035.4 41243.6 41408.0 3492.6 3550.1 3598.5 3700.3 3763.5 31880.5 32045.5 32359.0 32661.8 33308.3 4379.4 4472.7 4529.9 4639.4 4649.6 337969.0 339706.0 339838.0 340494.0 341309.3 33995.0 34799.5 56584.4 56771.0 56829.1 35852.1 36182.1 36190.8 36342.5 36407.6 14837.6 15083.3 15100.2 15113.2 15130.2 13260.3 13269.1 13408.1 13435.6 13457.7 8199.9 8288.4 8289.5 8358.7 8384.9 3216.2 3251.5 3255.4 3281.9 3318.4 1010.3 1029.7 1067.2 1086.3 1124.7 32244.2 32359.0 32551.8 32820.8 33300.5 1083.0 1093.2 1095.5 1099.8 1106.0 16948.4 17144.7 17155.1 17293.8 17338.5 507445.0 507454.0 507621.0 508142.0 512066.0 57282.4 58431.0 58620.0 58793.0 58910.0 1224.7 1251.4 1293.4 1298.3 1303.2 30398.5 32025.2 32046.9 32190.7 32288.8 48399.0 48496.0 48544.0 48577.8 48756.5 457309.0 458085.0 459639.0 461686.0 462368.0 27592.2 28341.1 28695.3 28867.6 28879.1 47496.5 47729.5 48655.3 48842.3 49111.8 39850.6 40452.6 40879.4 41679.6 42179.8 41036.8 41261.8 41329.8 41973.8 42120.0 39750.0 39896.8 40241.0 40599.8 40999.6 34908.5 36617.5 36778.3 36955.4 36960.6 27257.3 27736.6 27849.2 28052.7 28075.2 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - PROCESS | 27918 | [#2] jquery.html Cycles:180 Average:27576.82 Median:34070.60 stddev:73524.99 (215.8%) stddev-sans-first:73702.90 22:38:56 INFO - PROCESS | 27918 | Values: 386.3 391.9 393.5 394.8 409.4 13689.8 17946.6 18607.8 19495.2 19497.8 18021.5 18214.4 18231.1 18477.7 18501.3 5341.7 5612.9 5716.9 5717.0 5736.2 5605.1 5673.1 5687.6 5702.9 5725.0 5518.3 5606.2 5632.3 5634.1 5667.1 5448.4 5627.5 5655.6 5679.3 5723.6 5491.4 5529.7 5564.2 5808.2 5840.6 13036.3 13076.3 13358.7 13752.0 21843.5 2262.9 2327.2 2482.9 2758.2 2980.9 14752.4 15627.1 15649.8 15889.8 15946.7 1904.5 1953.8 2005.6 2078.3 2151.9 91757.5 95406.5 96121.5 96175.0 96461.0 8912.0 9409.9 9515.6 9566.3 9825.8 11920.8 12357.5 12846.0 12900.9 12954.7 7505.5 7544.6 7762.9 7894.6 8079.6 7922.6 7928.2 7936.2 7979.4 8141.1 4017.6 4038.0 4060.4 4068.0 4071.1 1859.3 1869.0 1882.8 1887.2 1921.8 885.9 933.8 1010.9 1038.6 1050.2 18414.0 18539.4 18662.7 18904.9 18978.1 979.5 1008.6 1028.6 1079.5 1086.4 10100.9 10746.9 10796.0 10812.9 10835.4 409721.0 425842.0 441775.0 465912.0 466050.0 87253.6 87391.6 87820.6 90156.0 90438.5 881.7 923.0 1034.7 1060.2 1068.1 15963.4 16211.7 16226.8 16402.3 16938.2 30816.4 37324.8 39431.0 39754.4 41865.8 54647.7 59802.4 59927.0 60236.0 60658.0 8575.7 8639.3 8682.2 8772.0 8777.7 13399.3 13477.1 13560.0 13672.4 13774.0 11605.8 11695.2 11807.6 11965.9 12003.2 11800.7 11829.9 12002.6 12015.3 12291.8 11237.8 11426.6 11942.5 11954.5 12039.6 7528.5 7891.0 8529.8 8602.3 8629.3 11505.9 11771.0 11815.7 12009.1 12225.2 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - PROCESS | 27918 | [#3] mootools.html Cycles:180 Average:5853.08 Median:2429.62 stddev:3434.67 (141.4%) stddev-sans-first:3428.28 22:38:56 INFO - PROCESS | 27918 | Values: 1437.8 1462.5 1518.1 1630.0 1631.4 8001.3 8712.9 8716.7 9333.7 9336.6 8754.5 8916.1 8921.5 8977.8 9025.5 2242.3 2271.6 2284.7 2285.3 2289.9 2210.3 2239.0 2293.5 2299.8 2299.9 2269.6 2299.6 2308.3 2317.8 2327.0 2219.3 2220.6 2221.8 2226.2 2231.4 2208.1 2212.9 2213.9 2214.2 2217.9 9098.3 9182.1 9182.6 9193.1 9195.6 5145.5 5255.3 5266.8 5270.7 5280.7 3081.9 3218.1 3359.9 3394.7 3404.3 1084.3 1092.4 1099.9 1113.1 1133.5 12666.9 12693.8 12694.2 12736.5 12736.7 4375.7 4420.3 4431.8 4454.6 4507.0 10373.8 10411.2 10522.9 10548.7 10597.6 5218.4 5256.3 5258.3 5263.2 5282.2 4212.9 4619.9 4779.3 4799.7 4877.8 3906.5 3928.9 4015.9 4021.4 4039.2 1273.2 1275.6 1277.6 1278.4 1299.5 891.3 914.2 949.1 956.0 995.3 5960.6 6041.3 6162.0 6194.3 6203.7 419.7 420.8 422.1 428.0 439.0 4178.9 4261.1 4279.7 4297.7 4297.9 11009.0 11430.3 11458.0 11460.4 11474.2 10363.6 11332.5 11380.3 11415.7 11416.8 8316.9 8360.6 8428.5 8446.7 8461.1 5717.3 5997.2 6000.5 6098.3 6136.7 9868.2 10104.2 10316.9 10340.5 10391.9 10372.4 10386.0 10443.6 10494.5 10601.8 7086.9 7623.5 7862.3 7883.7 8014.2 8667.3 8798.1 8809.3 8892.6 8892.9 7585.2 7615.8 7716.1 7733.4 7870.6 7552.5 7577.6 7600.7 7684.9 7694.7 7036.4 7051.0 7084.9 7087.8 7128.0 4907.8 4960.7 5004.5 5027.0 5079.4 5196.7 5228.5 5252.2 5329.3 5371.3 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - PROCESS | 27918 | [#4] prototype.html Cycles:180 Average:4854.24 Median:5577.07 stddev:1444.88 (25.9%) stddev-sans-first:1408.21 22:38:56 INFO - PROCESS | 27918 | Values: 316.0 321.9 325.9 328.1 331.7 5571.4 5577.2 5644.3 5693.9 5744.2 5489.6 5518.8 5525.9 5546.6 5556.2 4054.2 4220.8 4263.7 4272.7 4284.4 4253.5 4289.5 4292.8 4330.6 4333.2 4262.0 4288.6 4294.6 4296.3 4304.4 4008.0 4195.2 4198.9 4200.7 4250.8 3681.2 3686.9 3691.9 3695.6 3714.2 5483.5 5510.9 5514.4 5546.8 5557.0 4671.4 4904.0 4920.3 4926.3 4927.0 5807.0 5813.6 5824.7 5833.2 5838.3 4105.8 4118.8 4127.4 4139.9 4140.1 5925.5 5928.5 5962.5 5965.9 6143.5 4152.2 4161.6 4175.6 4227.0 4234.7 4211.4 4249.1 4252.3 4274.7 4289.1 4677.6 4850.7 4861.8 4891.4 4904.9 5808.3 5815.1 5888.3 5889.5 5907.6 1437.0 1448.8 1450.3 1452.8 1456.4 923.2 925.8 934.8 952.8 988.9 5568.4 5576.9 5583.9 5599.6 5599.6 5864.9 5874.4 5936.4 5979.2 6010.1 5236.5 5438.5 5513.3 5543.0 5627.5 3126.6 3146.4 3150.3 3151.3 3156.0 5864.6 6072.0 6078.7 6161.4 6177.5 5807.2 5919.9 6028.8 6049.5 6052.4 5999.5 6173.5 6179.9 6200.3 6251.9 5684.6 5917.2 5919.2 5919.3 5957.8 5805.8 5826.4 5865.5 5885.4 5936.2 5924.8 6055.8 6081.3 6087.1 6187.6 4120.4 4121.3 4131.1 4149.2 4170.4 5924.4 5925.2 5943.2 5972.0 5984.8 5490.9 5671.4 5715.3 5731.9 5745.6 5686.7 5709.9 5720.4 5727.5 5745.8 5452.7 5504.3 5616.5 5635.2 5636.5 5714.2 5862.6 5888.4 5889.6 5893.5 5513.8 5674.8 5706.0 5721.5 5726.2 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - PROCESS | 27918 | [#5] yui.html Cycles:180 Average:20178.28 Median:4641.08 stddev:28784.12 (620.2%) stddev-sans-first:28827.98 22:38:56 INFO - PROCESS | 27918 | Values: 772.4 775.1 780.4 783.9 841.9 13758.4 14338.0 15085.0 15140.2 15196.0 13793.4 13870.4 13886.7 13914.2 13970.3 766.3 770.6 778.1 780.8 796.7 798.2 798.4 800.7 802.6 804.0 840.9 842.0 843.0 843.4 844.1 831.0 834.1 836.2 837.2 838.5 604.7 608.5 608.9 609.3 610.3 15485.1 15509.1 15519.8 15536.3 15553.8 14495.9 14569.6 14571.3 14720.3 14787.8 12054.3 12056.8 12229.2 12273.6 12794.7 3829.8 3866.9 3980.0 4045.1 4052.0 77063.1 77702.2 78662.0 78857.6 79975.6 11003.2 11170.1 11248.3 11269.5 11601.5 20386.8 20443.0 20450.1 20453.5 20490.9 7964.7 8250.7 8427.8 8435.3 8548.7 4538.9 4569.8 4631.7 4650.5 4737.8 3406.4 3500.3 3516.3 3518.7 3530.3 577.8 584.9 589.4 598.5 603.4 625.4 664.8 706.0 733.9 753.4 9796.7 9808.2 10010.7 10159.4 10166.9 601.6 629.3 653.4 704.8 738.9 4674.3 4716.4 4762.3 4778.2 4837.3 79329.6 114288.4 114555.0 117387.4 117938.0 96712.0 97625.5 98358.0 98497.5 98866.5 68677.0 84403.6 84420.6 84911.0 87694.6 8946.9 9090.9 9194.3 9201.9 9348.4 35044.5 48209.5 48331.0 48554.6 49850.5 60469.4 63793.4 66127.0 71161.3 72001.0 10802.3 11921.8 13117.7 13147.2 13402.9 15056.3 15134.5 15215.0 15382.7 15512.6 13428.0 13515.5 13941.7 13984.9 14286.6 13809.2 13859.3 14146.2 14518.3 14703.3 11739.2 13370.8 13379.7 13789.0 13900.2 11963.3 12050.5 12058.2 12129.4 12466.5 10983.7 11551.7 11739.5 12000.4 12445.0 22:38:56 INFO - PROCESS | 27918 | -------- Summary: end -------- 22:38:56 INFO - PROCESS | 27918 | 22:38:56 INFO - TEST-INFO | 27918: exit 0 22:38:56 INFO - TEST-OK | dromaeo_css | took 1111557ms 22:38:56 INFO - TEST-START | kraken 22:38:56 INFO - operating with platform_type : linux_ 22:38:56 INFO - Initialising browser for kraken test... 22:38:56 INFO - TEST-INFO | started process 28157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7BiIod/profile http://localhost:43239/getInfo.html) 22:39:01 INFO - PROCESS | 28157 | __metrics Screen width/height:1600/1200 22:39:01 INFO - PROCESS | 28157 | colorDepth:24 22:39:01 INFO - PROCESS | 28157 | Browser inner width/height: 1024/697 22:39:01 INFO - PROCESS | 28157 | __metrics 22:39:01 ERROR - PROCESS | 28157 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 22:39:02 INFO - TEST-INFO | 28157: exit 0 22:39:02 INFO - Browser initialized. 22:39:02 INFO - Running cycle 1/1 for kraken test... 22:39:02 INFO - TEST-INFO | started process 28275 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7BiIod/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:39:14 INFO - PROCESS | 28275 | RSS: Main: 185020416 22:39:14 INFO - PROCESS | 28275 | 22:39:17 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43239/tests/kraken/driver.html?testName=audio-beat-detection) 22:39:18 INFO - PROCESS | 28275 | RSS: Main: 190918656 22:39:18 INFO - PROCESS | 28275 | 22:39:20 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43239/tests/kraken/driver.html?testName=audio-dft) 22:39:21 INFO - PROCESS | 28275 | RSS: Main: 190164992 22:39:21 INFO - PROCESS | 28275 | 22:39:24 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43239/tests/kraken/driver.html?testName=audio-fft) 22:39:25 INFO - PROCESS | 28275 | RSS: Main: 185753600 22:39:25 INFO - PROCESS | 28275 | 22:39:27 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43239/tests/kraken/driver.html?testName=audio-oscillator) 22:39:27 INFO - PROCESS | 28275 | RSS: Main: 185987072 22:39:27 INFO - PROCESS | 28275 | 22:39:30 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:39:30 INFO - PROCESS | 28275 | RSS: Main: 186580992 22:39:30 INFO - PROCESS | 28275 | 22:39:33 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43239/tests/kraken/driver.html?testName=imaging-darkroom) 22:39:34 INFO - PROCESS | 28275 | RSS: Main: 186654720 22:39:34 INFO - PROCESS | 28275 | 22:39:37 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43239/tests/kraken/driver.html?testName=imaging-desaturate) 22:39:38 INFO - PROCESS | 28275 | RSS: Main: 183328768 22:39:38 INFO - PROCESS | 28275 | 22:39:41 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43239/tests/kraken/driver.html?testName=json-parse-financial) 22:39:42 INFO - PROCESS | 28275 | RSS: Main: 185536512 22:39:42 INFO - PROCESS | 28275 | 22:39:43 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:39:44 INFO - PROCESS | 28275 | RSS: Main: 185794560 22:39:44 INFO - PROCESS | 28275 | 22:39:46 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43239/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:39:46 INFO - PROCESS | 28275 | RSS: Main: 185524224 22:39:46 INFO - PROCESS | 28275 | 22:39:48 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:39:49 INFO - PROCESS | 28275 | RSS: Main: 185991168 22:39:49 INFO - PROCESS | 28275 | 22:39:52 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:39:52 INFO - PROCESS | 28275 | RSS: Main: 185827328 22:39:52 INFO - PROCESS | 28275 | 22:39:55 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:39:56 INFO - PROCESS | 28275 | RSS: Main: 186077184 22:39:56 INFO - PROCESS | 28275 | 22:39:57 INFO - PROCESS | 28275 | Cycle 1(1): loaded http://localhost:43239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43239/tests/kraken/driver.html?testName=ai-astar) 22:39:58 INFO - PROCESS | 28275 | RSS: Main: 187314176 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | __start_tp_report 22:39:58 INFO - PROCESS | 28275 | _x_x_mozilla_page_load 22:39:58 INFO - PROCESS | 28275 | _x_x_mozilla_page_load_details 22:39:58 INFO - PROCESS | 28275 | |i|pagename|runs| 22:39:58 INFO - PROCESS | 28275 | |0;ai-astar;112;105;110;112;111;112;112;110;109;111 22:39:58 INFO - PROCESS | 28275 | |1;audio-beat-detection;150;132;160;182;128;158;158;126;185;128 22:39:58 INFO - PROCESS | 28275 | |2;audio-dft;161;165;164;162;168;176;165;162;160;161 22:39:58 INFO - PROCESS | 28275 | |3;audio-fft;80;75;76;153;75;78;76;76;162;75 22:39:58 INFO - PROCESS | 28275 | |4;audio-oscillator;85;85;153;86;87;85;143;85;87;156 22:39:58 INFO - PROCESS | 28275 | |5;imaging-gaussian-blur;118;119;118;117;118;117;117;117;118;118 22:39:58 INFO - PROCESS | 28275 | |6;imaging-darkroom;146;148;149;168;147;148;148;147;147;146 22:39:58 INFO - PROCESS | 28275 | |7;imaging-desaturate;95;95;96;96;95;97;96;97;96;96 22:39:58 INFO - PROCESS | 28275 | |8;json-parse-financial;55;56;62;56;57;54;55;55;56;56 22:39:58 INFO - PROCESS | 28275 | |9;json-stringify-tinderbox;61;62;62;62;63;62;61;62;61;61 22:39:58 INFO - PROCESS | 28275 | |10;stanford-crypto-aes;79;80;85;82;81;81;83;82;112;81 22:39:58 INFO - PROCESS | 28275 | |11;stanford-crypto-ccm;133;129;126;131;147;121;129;129;144;122 22:39:58 INFO - PROCESS | 28275 | |12;stanford-crypto-pbkdf2;163;169;171;162;166;169;159;162;163;165 22:39:58 INFO - PROCESS | 28275 | |13;stanford-crypto-sha256-iterative;69;65;64;64;76;67;64;63;63;63 22:39:58 INFO - PROCESS | 28275 | __end_tp_report 22:39:58 INFO - PROCESS | 28275 | __start_cc_report 22:39:58 INFO - PROCESS | 28275 | _x_x_mozilla_cycle_collect,915 22:39:58 INFO - PROCESS | 28275 | __end_cc_report 22:39:58 INFO - PROCESS | 28275 | __startTimestamp1466141998036__endTimestamp 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | ------- Summary: start ------- 22:39:58 INFO - PROCESS | 28275 | Number of tests: 14 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.50 stddev:2.17 (1.9%) stddev-sans-first:2.22 22:39:58 INFO - PROCESS | 28275 | Values: 112.0 105.0 110.0 112.0 111.0 112.0 112.0 110.0 109.0 111.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#1] audio-beat-detection Cycles:10 Average:150.70 Median:158.00 stddev:21.96 (13.9%) stddev-sans-first:23.29 22:39:58 INFO - PROCESS | 28275 | Values: 150.0 132.0 160.0 182.0 128.0 158.0 158.0 126.0 185.0 128.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.50 stddev:4.74 (2.9%) stddev-sans-first:4.87 22:39:58 INFO - PROCESS | 28275 | Values: 161.0 165.0 164.0 162.0 168.0 176.0 165.0 162.0 160.0 161.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#3] audio-fft Cycles:10 Average:92.60 Median:76.00 stddev:34.31 (45.1%) stddev-sans-first:36.08 22:39:58 INFO - PROCESS | 28275 | Values: 80.0 75.0 76.0 153.0 75.0 78.0 76.0 76.0 162.0 75.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#4] audio-oscillator Cycles:10 Average:105.20 Median:85.00 stddev:31.55 (37.1%) stddev-sans-first:32.60 22:39:58 INFO - PROCESS | 28275 | Values: 85.0 85.0 153.0 86.0 87.0 85.0 143.0 85.0 87.0 156.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 22:39:58 INFO - PROCESS | 28275 | Values: 118.0 119.0 118.0 117.0 118.0 117.0 117.0 117.0 118.0 118.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:148.00 stddev:6.60 (4.5%) stddev-sans-first:6.89 22:39:58 INFO - PROCESS | 28275 | Values: 146.0 148.0 149.0 168.0 147.0 148.0 148.0 147.0 147.0 146.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 22:39:58 INFO - PROCESS | 28275 | Values: 95.0 95.0 96.0 96.0 95.0 97.0 96.0 97.0 96.0 96.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:2.20 (3.9%) stddev-sans-first:2.29 22:39:58 INFO - PROCESS | 28275 | Values: 55.0 56.0 62.0 56.0 57.0 54.0 55.0 55.0 56.0 56.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 22:39:58 INFO - PROCESS | 28275 | Values: 61.0 62.0 62.0 62.0 63.0 62.0 61.0 62.0 61.0 61.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:81.50 stddev:9.77 (12.0%) stddev-sans-first:10.15 22:39:58 INFO - PROCESS | 28275 | Values: 79.0 80.0 85.0 82.0 81.0 81.0 83.0 82.0 112.0 81.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#11] stanford-crypto-ccm Cycles:10 Average:131.10 Median:130.00 stddev:8.48 (6.5%) stddev-sans-first:8.96 22:39:58 INFO - PROCESS | 28275 | Values: 133.0 129.0 126.0 131.0 147.0 121.0 129.0 129.0 144.0 122.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:165.50 stddev:3.81 (2.3%) stddev-sans-first:3.98 22:39:58 INFO - PROCESS | 28275 | Values: 163.0 169.0 171.0 162.0 166.0 169.0 159.0 162.0 163.0 165.0 22:39:58 INFO - PROCESS | 28275 | 22:39:58 INFO - PROCESS | 28275 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:64.50 stddev:4.08 (6.3%) stddev-sans-first:4.16 22:39:58 INFO - PROCESS | 28275 | Values: 69.0 65.0 64.0 64.0 76.0 67.0 64.0 63.0 63.0 63.0 22:39:58 INFO - PROCESS | 28275 | -------- Summary: end -------- 22:39:58 INFO - PROCESS | 28275 | 22:39:59 INFO - TEST-INFO | 28275: exit 0 22:39:59 INFO - TEST-OK | kraken | took 62119ms 22:39:59 INFO - SUITE-END | took 1173s 22:39:59 INFO - Completed test suite (00:19:33) 22:39:59 INFO - javascript benchmark 22:39:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7173.113227590146, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3085.8765649374027, 3166.6674999125075, 3180.268538236207, 3182.8408579571014, 3204.2149673330023, 5424.0236757383655, 5426.131818908975, 5470.452954704528, 5544.16837474379, 5572.108462645978, 5425.9186112208345, 5428.0, 5469.206964990074, 5475.4524547545225, 5577.776888924438, 4903.411590609123, 4929.4824043475455, 5025.296458495814, 5060.7722652480725, 5126.051680439112, 602.1233478596155, 4988.675736077162, 4994.1260279451135, 5021.046001259767, 5040.042391945519, 4901.240307752304, 4930.161872481669, 4940.061388336205, 4953.4303555091265, 5003.624728145403, 4876.244182151737, 4968.627352948543, 4976.726280054596, 4980.601551875841, 5021.39743230811, 4809.158397280462, 4864.878378040542, 4872.878178045542, 4912.754362281871, 4916.50834916509, 5365.973170134163, 5369.785208591652, 5413.83758487246, 5419.458054194589, 5476.288082549283, 5648.237487939116, 5793.9130913035915, 5795.652260864362, 5797.246357973479, 5821.18503409523, 5897.705114744289, 5934.287885453773, 5949.9702501487645, 5955.3151387590115, 6018.217631707894, 5543.0, 5550.111982082847, 5553.028220061514, 5557.027520183951, 5557.3331200256225, 5893.0, 5928.762849486058, 5966.820995370059, 6034.577579569388, 6047.395260474005, 5813.970930145407, 5820.679862607597, 5822.155787410887, 5823.737931793079, 5863.3257175424515, 5873.618214816023, 5894.557908156906, 5902.3507414184405, 5909.497692696083, 5915.58590898633, 5656.349519805192, 5746.770129194785, 5747.597668163272, 5862.824115276549, 5896.528277737771, 5734.139879018097, 5763.049096898964, 5789.884202316014, 5872.677002764804, 5909.202257695156, 4469.307257375112, 4477.328400739915, 4485.304777759453, 4486.551344865487, 4512.187806194851, 3950.585188555218, 3955.841766329372, 3997.0, 4008.7394319369146, 4057.2696914555663, 5706.201131841545, 5712.11462223356, 5715.514181294637, 5718.914216286676, 5725.0, 5941.58408911372, 6014.6391216527145, 6018.608790428608, 6022.09668549721, 6023.1266466363, 5489.945100549024, 5525.640833345819, 5540.307461567304, 5593.692346920959, 5718.771249149988, 5087.745612719349, 5093.057784309841, 5093.617978651616, 5102.515261050195, 5104.795808167706, 6139.907901381483, 6156.29202641693, 6162.722677479434, 6166.044263139131, 6168.537359698041, 5792.189093526994, 5823.70881455934, 5832.795852145239, 5853.502452291603, 5868.67722275279, 6063.423974722395, 6064.757409703562, 6073.331933487401, 6080.300765412034, 6084.087386892002, 5529.225908372911, 5741.712914354349, 5758.769649213985, 5759.424057594207, 5761.452661997104, 5822.505087067647, 5885.14665373527, 5894.734736936763, 5933.643981361132, 5946.643201407929, 5724.513416359656, 5771.0, 5793.9420605795085, 5802.6808525531505, 5815.302163740378, 5565.220869078414, 5570.91643625354, 5735.483806457439, 5775.653460792366, 5803.912941305968, 5496.587755918402, 5521.199426083276, 5532.280803495681, 5592.608517403743, 5593.300837395325, 5754.971225143848, 5759.0, 5774.595778295479, 5779.682117483579, 5780.421957804186, 5559.833205003856, 5589.916151257653, 5651.208830763614, 5654.293213348332, 5654.717264136694], "value": 5375.932355236511, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.3882764855429, 595.1845971019702, 602.1509671363378, 625.024961060745, 628.6134027573044, 42209.366859497095, 43056.3541546877, 43089.13821723564, 43267.13465730684, 47213.29180062302, 44185.77907110468, 44612.3308150378, 45019.54980450195, 45024.0, 45167.322490162594, 8779.2098711116, 8920.509371984539, 8967.372283940127, 9015.098490150998, 9042.457452552835, 8740.694075707352, 8838.911610883873, 8862.60118294675, 8930.910690893106, 8931.24084452819, 8891.733248002538, 8932.151445612657, 8972.910270897306, 9005.50469724165, 9007.369484136114, 8850.5132217728, 8885.644574217024, 8958.104189581056, 8974.551272436385, 8974.910250897472, 8767.430117042371, 8867.068957759408, 8880.067592902717, 8909.55452227386, 8910.821783564357, 40818.0, 40875.18249634991, 41035.38446923269, 41243.587564124275, 41408.0, 3492.5808902931813, 3550.130218096557, 3598.514200582914, 3700.2599480104086, 3763.4919285896326, 31880.521792173135, 32045.51931721026, 32359.0, 32661.836690816395, 33308.333833323195, 4379.364992076131, 4472.686911916167, 4529.932051019237, 4639.350490931272, 4649.604783593399, 337969.0, 339706.0, 339838.0, 340494.0, 341309.2934535336, 33995.0, 34799.4780078299, 56584.43415565792, 56771.0, 56829.147562786595, 35852.10369740725, 36182.095447613494, 36190.819045905126, 36342.454863177074, 36407.635923640424, 14837.62905927339, 15083.321250543751, 15100.244987750568, 15113.16877571744, 15130.167840768863, 13260.336983150804, 13269.071165018357, 13408.061435699407, 13435.596932092052, 13457.663558411117, 8199.877001844978, 8288.378371622153, 8289.461185022954, 8358.707445239388, 8384.8742268866, 3216.244182617083, 3251.512273159045, 3255.4140254753897, 3281.9015429537153, 3318.369509793132, 1010.2877471382658, 1029.6626657487461, 1067.215596536545, 1086.3047649504324, 1124.7244425115734, 32244.19389515281, 32359.0, 32551.83724081412, 32820.83589582085, 33300.50049249263, 1082.964404121421, 1093.1801149138146, 1095.4741723972538, 1099.8185299425504, 1106.0377471599759, 16948.40680576174, 17144.657106857794, 17155.142242887807, 17293.827061729222, 17338.479845604652, 507445.0, 507454.0, 507621.0, 508142.0, 512066.0, 57282.42717572771, 58431.0, 58620.0, 58793.0, 58910.0, 1224.6570960131178, 1251.4118364368733, 1293.4373547506864, 1298.2859427315127, 1303.2245813740808, 30398.54402184013, 32025.199370015936, 32046.93144386851, 32190.678093219703, 32288.83855580707, 48399.0, 48496.0, 48544.0, 48577.7855553614, 48756.51243487662, 457309.0, 458085.0, 459639.0, 461686.0, 462368.0, 27592.172234832986, 28341.149765507067, 28695.282617934718, 28867.5669864948, 28879.133625991253, 47496.52503474921, 47729.522704773895, 48655.27017094817, 48842.26736598883, 49111.75444122757, 39850.60149398469, 40452.59547404488, 40879.386809198484, 41679.58320416757, 42179.789101054295, 41036.79481602573, 41261.79369103135, 41329.77591216663, 41973.79013104915, 42120.0, 39750.0, 39896.80051599723, 40241.0, 40599.797001014806, 40999.59000409958, 34908.47637285392, 36617.45073823842, 36778.26443471169, 36955.44566831446, 36960.63039369572, 27257.31856703598, 27736.58395124115, 27849.16452506428, 28052.71947280501, 28075.157745267676], "value": 18834.286639154776, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.29693956998267, 391.89222963684983, 393.46881709691917, 394.8243031850827, 409.43497972797536, 13689.794653080211, 17946.551336216584, 18607.813921860776, 19495.22019119237, 19497.805021949793, 18021.4593562193, 18214.3941392233, 18231.0884455777, 18477.722834157466, 18501.259949602, 5341.65279256849, 5612.859678508029, 5716.8856622867515, 5717.028105222123, 5736.168255602956, 5605.103183490643, 5673.092305231164, 5687.573431992597, 5702.857428564298, 5725.0, 5518.255035570207, 5606.21512988182, 5632.267805185341, 5634.0703783875615, 5667.064934285859, 5448.37343705475, 5627.521660658849, 5655.57583181261, 5679.261695979538, 5723.627964182315, 5491.36849262332, 5529.668219906778, 5564.165375193713, 5808.2449281593545, 5840.620359676607, 13036.348182590833, 13076.346182690828, 13358.732825343439, 13752.0, 21843.453913652287, 2262.8529145605485, 2327.2203811723093, 2482.888270027835, 2758.241483592008, 2980.8658610362586, 14752.409903603842, 15627.140507272095, 15649.765253521206, 15889.841101588952, 15946.681066378607, 1904.4572296895458, 1953.8143876331726, 2005.6089062632645, 2078.3349328214977, 2151.9462013449634, 91757.54121229418, 95406.52296738542, 96121.5193924033, 96175.0, 96461.0, 8912.0, 9409.858852117224, 9515.571799268911, 9566.282528810367, 9825.803483930282, 11920.821187682192, 12357.505699772088, 12846.0, 12900.935495322463, 12954.740905181843, 7505.4746167767735, 7544.622768861535, 7762.883556746653, 7894.64474098667, 8079.555624440713, 7922.564258965697, 7928.1675424080795, 7936.16670249627, 7979.361651068017, 8141.063777665526, 4017.598240175959, 4037.9596204037584, 4060.4315395844606, 4067.9593204068174, 4071.1247081877536, 1859.3399343233227, 1868.990655046716, 1882.8399586035066, 1887.169645356039, 1921.7597800274966, 885.9379843411028, 933.8459154239474, 1010.9241806864515, 1038.6468600675807, 1050.175612144463, 18414.0, 18539.44381668552, 18662.720059199124, 18904.905475472533, 18978.051097445074, 979.485769970771, 1008.6016023670609, 1028.6091285311534, 1079.4548752879903, 1086.3699054548342, 10100.949495252476, 10746.946265268623, 10796.0, 10812.918708129124, 10835.404052777172, 409721.0, 425842.0, 441775.0, 465912.0, 466050.0, 87253.56373218093, 87391.56304218438, 87820.5608971951, 90156.0, 90438.54780726055, 881.7002219245359, 922.9708874604768, 1034.7257976636197, 1060.151878497199, 1068.0761141612604, 15963.361465541248, 16211.675766484841, 16226.837731622532, 16402.26189821485, 16938.23777930021, 30816.383672326876, 37324.813375934034, 39431.0, 39754.40368394418, 41865.79067104645, 54647.726761365935, 59802.401975981425, 59927.0, 60236.0, 60658.0, 8575.713642953608, 8639.308855291436, 8682.175193356621, 8771.956140219258, 8777.736667899973, 13399.263040532864, 13477.123986940818, 13560.0, 13672.384742686405, 13774.0, 11605.825912611486, 11695.181337306307, 11807.63249192468, 11965.880341196476, 12003.219790713576, 11800.70498237551, 11829.940850295694, 12002.639920802041, 12015.279083255033, 12291.815622765487, 11237.831432528355, 11426.600068997544, 11942.522299107939, 11954.521819127138, 12039.578614748792, 7528.473006889684, 7890.960545197467, 8529.786755331166, 8602.311815054656, 8629.309655227442, 11505.942470287595, 11771.0, 11815.704607384883, 12009.099317551394, 12225.205361651464], "value": 9040.111082966567, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.777144542596, 1462.5173692681415, 1518.119490695396, 1629.9918500407496, 1631.4289998500526, 8001.319887809529, 8712.912870871298, 8716.651333946635, 9333.673321433751, 9336.626534938603, 8754.518501482416, 8916.063813299608, 8921.509316987564, 8977.775555611113, 9025.458472491655, 2242.3048854855006, 2271.613825649635, 2284.6915666385075, 2285.3486756274465, 2289.942751431211, 2210.270610698474, 2238.955220895581, 2293.495431005176, 2299.827512936528, 2299.942501437469, 2269.614165591845, 2299.620562607168, 2308.272894038381, 2317.756635553268, 2326.9883650581723, 2219.289827255279, 2220.6113930062256, 2221.833362497811, 2226.209695558078, 2231.4309850988056, 2208.1277895231456, 2212.8782916939563, 2213.889305534733, 2214.2139540463227, 2217.9223727169633, 9098.272138228926, 9182.081791820765, 9182.632694692204, 9193.126652968023, 9195.586198621011, 5145.4597267287145, 5255.290535777659, 5266.7629956652045, 5270.6837589744355, 5280.735963201863, 3081.9229519261976, 3218.1150183699438, 3359.9160020999425, 3394.745394095428, 3404.3361544498584, 1084.3006260961715, 1092.355510248949, 1099.8735145458295, 1113.0817075912405, 1133.489929531714, 12666.936665316707, 12693.809592856114, 12694.238345699288, 12736.490540378281, 12736.745265094738, 4375.693701440932, 4420.270655341897, 4431.82272709088, 4454.554544545585, 4506.954930450719, 10373.84439233412, 10411.219158563137, 10522.894771052344, 10548.736281592868, 10597.62908298206, 5218.373795144607, 5256.316678831728, 5258.316418865523, 5263.184206448007, 5282.181261904411, 4212.873613791591, 4619.8845028873875, 4779.330893674888, 4799.688020278671, 4877.780499877514, 3906.4726261954843, 3928.901777455529, 4015.859444919414, 4021.4369988201674, 4039.1921615676983, 1273.2106094221642, 1275.5663074554695, 1277.5975567696332, 1278.4374875054946, 1299.4802079168408, 891.3047822698305, 914.2492127575752, 949.0746522140995, 955.9761005974767, 995.328153496387, 5960.612560183661, 6041.335453100155, 6161.983272760034, 6194.287656919422, 6203.689815509207, 419.6831392298795, 420.82956402656976, 422.0882892951272, 427.9850205242802, 438.96927215095275, 4178.916421671611, 4261.062566235485, 4279.700420970565, 4297.656187504992, 4297.88255053682, 11009.0, 11430.314181149157, 11458.0, 11460.426978651034, 11474.196806223485, 10363.585456581652, 11332.546698132148, 11380.260283081574, 11415.714607134889, 11416.828747568794, 8316.87722157501, 8360.623771930277, 8428.536430496382, 8446.704365347183, 8461.069282379056, 5717.256756621613, 5997.16039754426, 6000.489958353589, 6098.298695650057, 6136.662483563447, 9868.210543156674, 10104.242181836538, 10316.948415257875, 10340.482975851028, 10391.948040259751, 10372.429516376671, 10386.0, 10443.582256709646, 10494.47527623631, 10601.840972385266, 7086.893696594452, 7623.542587444771, 7862.253085956825, 7883.7240696575345, 8014.19858014194, 8667.263282620796, 8798.076201998698, 8809.251213646912, 8892.64429422816, 8892.911070889468, 7585.16563178061, 7615.809604759926, 7716.112647045662, 7733.419993500397, 7870.606469676379, 7552.509086909334, 7577.583232922242, 7600.657970391235, 7684.884726728992, 7694.692212311445, 7036.401905837856, 7050.964745176241, 7084.929150708427, 7087.82280442993, 7127.964360178166, 4907.754612269301, 4960.72716000609, 5004.549590536869, 5027.044861476308, 5079.415867175322, 5196.662216955886, 5228.503292187236, 5252.238425428368, 5329.307190065422, 5371.274877891435], "value": 4549.4396963934205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.95576619273294, 321.92273854274976, 325.9266665000375, 328.0519299225238, 331.71638249296865, 5571.4428557144265, 5577.219189313494, 5644.322681278242, 5693.886122277557, 5744.224529688491, 5489.64317319374, 5518.806841760539, 5525.922445123203, 5546.611737178399, 5556.194351818984, 4054.2499637567, 4220.767857767822, 4263.680223983198, 4272.658187345005, 4284.400183974246, 4253.468316460442, 4289.528151903288, 4292.849750258742, 4330.610245077943, 4333.245035760507, 4262.0, 4288.571142885702, 4294.561321957132, 4296.312589985603, 4304.397384366192, 4007.9599204008023, 4195.160967806451, 4198.916021679549, 4200.663946884242, 4250.766207858566, 3681.1901381695984, 3686.944695829565, 3691.8523259069607, 3695.6489133532273, 3714.220013797079, 5483.533899618537, 5510.9448905510835, 5514.393416724157, 5546.77812887484, 5557.0, 4671.392718946515, 4903.975480122613, 4920.261960705887, 4926.285688575173, 4926.975365123151, 5807.0, 5813.563982701315, 5824.737886795062, 5833.212516310286, 5838.299404071538, 4105.774182419966, 4118.75287482749, 4127.442795222637, 4139.8965025874295, 4140.0891803803115, 5925.466707996301, 5928.458600763833, 5962.522998160137, 5965.910511342333, 6143.508519318444, 4152.1695660867945, 4161.625453709181, 4175.5824417558, 4227.027783609787, 4234.7247428917635, 4211.431456753299, 4249.086446414036, 4252.277112890816, 4274.658027357804, 4289.0992891492515, 4677.579017888406, 4850.660453768202, 4861.763588749215, 4891.437484689306, 4904.852854414374, 5808.332041815161, 5815.127730840408, 5888.32182827892, 5889.469947704727, 5907.556933230025, 1436.9712605747827, 1448.7971683964254, 1450.2531196433845, 1452.8038714773581, 1456.3519233940794, 923.1922068190333, 925.833349997007, 934.8317302885546, 952.8094381123803, 988.9456079915674, 5568.387477377485, 5576.9163462548095, 5583.883223355345, 5599.552035837123, 5599.580031497654, 5864.882702346014, 5874.416605870416, 5936.436038576328, 5979.1629171915965, 6010.136857574166, 5236.512656488214, 5438.510534051954, 5513.338399392099, 5542.972285138549, 5627.549796016309, 3126.5622812806228, 3146.4179126861604, 3150.3069324748517, 3151.306712523241, 3155.984220078931, 5864.589478736448, 6072.0, 6078.726457309432, 6161.4146656067605, 6177.536684748662, 5807.186993820868, 5919.934411805916, 6028.788992385245, 6049.455549000611, 6052.425019623129, 5999.490043346277, 6173.536984726164, 6179.938200617937, 6200.255969283625, 6251.874962500725, 5684.635687435069, 5917.230760001173, 5919.171316015675, 5919.319278283053, 5957.791477298274, 5805.796797112081, 5826.446487583673, 5865.472107510344, 5885.440358305138, 5936.168936349001, 5924.822255332347, 6055.819115272479, 6081.300650425258, 6087.139044649008, 6187.597806142587, 4120.434234990685, 4121.278776214211, 4131.091159944807, 4149.190907773076, 4170.436991006175, 5924.407559244041, 5925.200097986716, 5943.197668314722, 5971.970140149271, 5984.760609575568, 5490.917636235539, 5671.376148623731, 5715.257016587817, 5731.885362292647, 5745.597808153389, 5686.71566421669, 5709.857253568694, 5720.427957204246, 5727.4845263926445, 5745.827625171251, 5452.727363631723, 5504.256925315191, 5616.511624419614, 5635.239242702182, 5636.5208957237455, 5714.200011998407, 5862.589618726819, 5888.440598143169, 5889.557058520519, 5893.499052580579, 5513.779448822002, 5674.829755107354, 5706.0, 5721.542276617777, 5726.198332233574], "value": 4384.22797008663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.4399810137651, 775.1434664695507, 780.4192899074673, 783.8667426537488, 841.9115992820754, 13758.44966201352, 14338.0, 15085.0, 15140.242987850605, 15196.0, 13793.44826206953, 13870.445182192725, 13886.722265554683, 13914.234717090556, 13970.301484925763, 766.2643861892576, 770.5761830992959, 778.097407007871, 780.8438312337527, 796.749024057423, 798.1659166171336, 798.3573223555036, 800.7277525641281, 802.5545822068749, 803.9879401808978, 840.9243168114868, 841.9957900210521, 842.9578521073953, 843.4138273899642, 844.0968164064453, 830.9542975136366, 834.0741776627938, 836.1972506393839, 837.1753822484878, 838.4717627894448, 604.694629212245, 608.5253502268238, 608.9360617135181, 609.2780055634088, 610.261583483985, 15485.070895746292, 15509.069455832687, 15519.844801551953, 15536.300866461037, 15553.766693499607, 14495.855041449557, 14569.562913112517, 14571.344289506998, 14720.263986800725, 14787.778183327258, 12054.276743395425, 12056.758864822743, 12229.205101668364, 12273.57042503517, 12794.680132996657, 3829.751066180689, 3866.9226615467533, 3980.0, 4045.1302969861326, 4052.0275133967634, 77063.07404779278, 77702.22297777007, 78662.0, 78857.60571197107, 79975.6001219996, 11003.22977391575, 11170.106391488662, 11248.26886252391, 11269.54921803135, 11601.477933493014, 20386.79613203849, 20443.0, 20450.079746411448, 20453.48866278355, 20490.897545512176, 7964.681412743541, 8250.711225107212, 8427.831443371098, 8435.283000944866, 8548.700795472252, 4538.931916021263, 4569.81720731167, 4631.698939568917, 4650.465196502378, 4737.786799594027, 3406.369821583015, 3500.334936362083, 3516.3494753470177, 3518.6980817097324, 3530.3292374448774, 577.8150991682621, 584.9122631605293, 589.3546566509665, 598.4853026397295, 603.4177019176516, 625.4246093593905, 664.8304682305981, 705.9788206353818, 733.8532293541313, 753.3634079203065, 9796.746748247942, 9808.21534277256, 10010.699679009642, 10159.390436573829, 10166.898331016742, 601.6209787833636, 629.2763322179438, 653.4119292636665, 704.7531130731653, 738.8596166728305, 4674.293371715003, 4716.410448693913, 4762.261849413347, 4778.211595086772, 4837.298591704253, 79329.60335198288, 114288.42855785834, 114555.0, 117387.41306293414, 117938.0, 96712.0, 97625.51187244018, 98358.0, 98497.50751246198, 98866.5056674712, 68677.0, 84403.5779821097, 84420.57789711012, 84911.0, 87694.56152719195, 8946.86579701292, 9090.909090909006, 9194.264458843143, 9201.907980920105, 9348.392354496937, 35044.474332884514, 48209.5179048205, 48331.0, 48554.572271387, 49850.501494986034, 60469.395306048136, 63793.362066378744, 66127.0, 71161.28838711546, 72001.0, 10802.297850639683, 11921.82117268259, 13117.672058198621, 13147.211167329991, 13402.93298533501, 15056.30708982303, 15134.50129681712, 15215.0, 15382.692346153239, 15512.612184695472, 13428.0, 13515.459381624625, 13941.721165576835, 13984.930075349557, 14286.642833929234, 13809.240491772647, 13859.30703464843, 14146.221957792008, 14518.346674399467, 14703.264836757906, 11739.201937596326, 13370.799438008244, 13379.665508362368, 13789.0, 13900.16599004063, 11963.342016189194, 12050.457729402373, 12058.15592908488, 12129.393530323625, 12466.5013399463, 10983.725406864893, 11551.71120721955, 11739.47172377228, 12000.39998000079, 12445.0], "value": 7460.655773757264, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 110.0, 112.0, 111.0, 112.0, 112.0, 110.0, 109.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 132.0, 160.0, 182.0, 128.0, 158.0, 158.0, 126.0, 185.0, 128.0], "value": 150.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 165.0, 164.0, 162.0, 168.0, 176.0, 165.0, 162.0, 160.0, 161.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 76.0, 153.0, 75.0, 78.0, 76.0, 76.0, 162.0, 75.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 153.0, 86.0, 87.0, 85.0, 143.0, 85.0, 87.0, 156.0], "value": 105.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 149.0, 168.0, 147.0, 148.0, 148.0, 147.0, 147.0, 146.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 97.0, 96.0, 97.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 62.0, 56.0, 57.0, 54.0, 55.0, 55.0, 56.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 63.0, 62.0, 61.0, 62.0, 61.0, 61.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 85.0, 82.0, 81.0, 81.0, 83.0, 82.0, 112.0, 81.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 129.0, 126.0, 131.0, 147.0, 121.0, 129.0, 129.0, 144.0, 122.0], "value": 131.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 169.0, 171.0, 162.0, 166.0, 169.0, 159.0, 162.0, 163.0, 165.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 64.0, 64.0, 76.0, 67.0, 64.0, 63.0, 63.0, 63.0], "value": 65.8, "unit": "score"}]}]} 22:39:59 INFO - Return code: 0 22:39:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:39:59 ERROR - # TBPL SUCCESS # 22:39:59 INFO - Running post-action listener: _resource_record_post_action 22:39:59 INFO - ##### 22:39:59 INFO - ##### Finished run-tests step (success) 22:39:59 INFO - ##### 22:39:59 INFO - Running post-run listener: _resource_record_post_run 22:39:59 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 549138432; Read time: 20; Write time: 418444 22:39:59 INFO - TinderboxPrint: CPU usage
13.4% 22:39:59 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 22:39:59 INFO - TinderboxPrint: I/O write bytes / time
549,138,432 / 418,444 22:39:59 INFO - TinderboxPrint: CPU idle
8,228.8 (86.6%) 22:39:59 INFO - TinderboxPrint: CPU user
1,222.8 (12.9%) 22:39:59 INFO - TinderboxPrint: Swap in / out
0 / 0 22:39:59 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 255033344; Read time: 0; Write time: 253468 22:39:59 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 8192; Write bytes: 293609472; Read time: 20; Write time: 164976 22:39:59 INFO - Running post-run listener: _upload_blobber_files 22:39:59 INFO - Blob upload gear active. 22:39:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:39:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:39:59 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:39:59 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:40:00 INFO - (blobuploader) - INFO - Open directory for files ... 22:40:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:40:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:40:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:01 INFO - (blobuploader) - INFO - Done attempting. 22:40:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:40:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:40:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:01 INFO - (blobuploader) - INFO - Done attempting. 22:40:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:40:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:40:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:40:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:40:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:40:05 INFO - (blobuploader) - INFO - Done attempting. 22:40:05 INFO - (blobuploader) - INFO - Iteration through files over. 22:40:05 INFO - Return code: 0 22:40:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:40:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:40:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd90fe6b95d7ad38a01721872b80cdd40cede950aba21e18900cd0e101c97c97b5c2d29cc6174a571e55f54783da0c5ff035d1f4dc55dbb4327ef8fe952f6a7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e88b2b60ec448325f27c030578707dd658dc14706cde84e496292e77440530083545e32eac7e244749209ba7d11c5463522c509fe3cfd911bfdaff9236c2021", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0dcdbecfdb8f4781a11d9ffe5e138427288e5a45f103ce3526f1756d0a71efc3e5a70cfa4a730450a0c69cd9474e7f3a274f994c042d49561e12fe0f6a7a5ef"} 22:40:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:40:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:40:05 INFO - Contents: 22:40:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd90fe6b95d7ad38a01721872b80cdd40cede950aba21e18900cd0e101c97c97b5c2d29cc6174a571e55f54783da0c5ff035d1f4dc55dbb4327ef8fe952f6a7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e88b2b60ec448325f27c030578707dd658dc14706cde84e496292e77440530083545e32eac7e244749209ba7d11c5463522c509fe3cfd911bfdaff9236c2021", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0dcdbecfdb8f4781a11d9ffe5e138427288e5a45f103ce3526f1756d0a71efc3e5a70cfa4a730450a0c69cd9474e7f3a274f994c042d49561e12fe0f6a7a5ef"} 22:40:05 INFO - Running post-run listener: copy_logs_to_upload_dir 22:40:05 INFO - Copying logs to upload dir... 22:40:05 INFO - mkdir: /builds/slave/test/build/upload/logs 22:40:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.789610 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-06-16 22:40:05.001771) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 22:40:05.004960) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd90fe6b95d7ad38a01721872b80cdd40cede950aba21e18900cd0e101c97c97b5c2d29cc6174a571e55f54783da0c5ff035d1f4dc55dbb4327ef8fe952f6a7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e88b2b60ec448325f27c030578707dd658dc14706cde84e496292e77440530083545e32eac7e244749209ba7d11c5463522c509fe3cfd911bfdaff9236c2021", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0dcdbecfdb8f4781a11d9ffe5e138427288e5a45f103ce3526f1756d0a71efc3e5a70cfa4a730450a0c69cd9474e7f3a274f994c042d49561e12fe0f6a7a5ef"} build_url:https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011542 build_url: 'https://queue.taskcluster.net/v1/task/PoX__xkhRHyVDHXJ5mJayg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd90fe6b95d7ad38a01721872b80cdd40cede950aba21e18900cd0e101c97c97b5c2d29cc6174a571e55f54783da0c5ff035d1f4dc55dbb4327ef8fe952f6a7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e88b2b60ec448325f27c030578707dd658dc14706cde84e496292e77440530083545e32eac7e244749209ba7d11c5463522c509fe3cfd911bfdaff9236c2021", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0dcdbecfdb8f4781a11d9ffe5e138427288e5a45f103ce3526f1756d0a71efc3e5a70cfa4a730450a0c69cd9474e7f3a274f994c042d49561e12fe0f6a7a5ef"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 22:40:05.031037) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 22:40:05.031418) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1466140656.455220-1862000809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004564 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 22:40:05.113519) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 22:40:05.113842) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 22:40:05.114133) ========= ========= Total master_lag: 0.17 =========