builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1465278639.33 results: success (0) buildid: 20160606202128 builduid: 7ec3cba5ae654a229f94a8ace0e91e74 revision: e70892847544db94d801f80543fe8a7f6d836f90 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.328268) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.328862) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.329262) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.345860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.346278) ========= 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=af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-06 22:50:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.8M=0s 2016-06-06 22:50:39 (37.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074222 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.437547) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.437880) ========= 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=af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017585 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.492226) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:39.492611) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e70892847544db94d801f80543fe8a7f6d836f90 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e70892847544db94d801f80543fe8a7f6d836f90 --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=af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-06 22:50:39,729 truncating revision to first 12 chars 2016-06-06 22:50:39,730 Setting DEBUG logging. 2016-06-06 22:50:39,730 attempt 1/10 2016-06-06 22:50:39,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e70892847544?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 22:50:40,143 unpacking tar archive at: mozilla-aurora-e70892847544/testing/mozharness/ program finished with exit code 0 elapsedTime=0.744155 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:40.250983) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:40.251346) ========= script_repo_revision: e70892847544db94d801f80543fe8a7f6d836f90 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:40.251830) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:40.252143) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 22:50:40.261034) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-06-06 22:50:40.261344) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:50:40 INFO - MultiFileLogger online at 20160606 22:50:40 in /builds/slave/test 22:50:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 22:50:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:50:40 INFO - {'append_to_log': False, 22:50:40 INFO - 'base_work_dir': '/builds/slave/test', 22:50:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:50:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:50:40 INFO - 'branch': 'Mozilla-Aurora', 22:50:40 INFO - 'buildbot_json_path': 'buildprops.json', 22:50:40 INFO - 'config_files': ('talos/linux_config.py',), 22:50:40 INFO - 'default_actions': ('clobber', 22:50:40 INFO - 'read-buildbot-config', 22:50:40 INFO - 'download-and-extract', 22:50:40 INFO - 'populate-webroot', 22:50:40 INFO - 'create-virtualenv', 22:50:40 INFO - 'install', 22:50:40 INFO - 'run-tests'), 22:50:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:50:40 INFO - 'download_minidump_stackwalk': True, 22:50:40 INFO - 'download_symbols': 'ondemand', 22:50:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:50:40 INFO - 'tooltool.py': '/tools/tooltool.py', 22:50:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:50:40 INFO - '/tools/misc-python/virtualenv.py')}, 22:50:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:50:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:50:40 INFO - 'installer_path': 'installer.exe', 22:50:40 INFO - 'log_level': 'info', 22:50:40 INFO - 'log_name': 'talos', 22:50:40 INFO - 'log_to_console': True, 22:50:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:50:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:50:40 INFO - 'opt_config_files': (), 22:50:40 INFO - 'pip_index': False, 22:50:40 INFO - 'sps_profile': False, 22:50:40 INFO - 'sps_profile_interval': 0, 22:50:40 INFO - 'suite': 'dromaeojs', 22:50:40 INFO - 'system_bits': '32', 22:50:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:50:40 INFO - 'title': 'talos-linux64-ix-020', 22:50:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:50:40 INFO - 'use_talos_json': True, 22:50:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:50:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:50:40 INFO - 'work_dir': 'build'} 22:50:40 INFO - ##### 22:50:40 INFO - ##### Running clobber step. 22:50:40 INFO - ##### 22:50:40 INFO - Running pre-action listener: _resource_record_pre_action 22:50:40 INFO - Running main action method: clobber 22:50:40 INFO - rmtree: /builds/slave/test/build 22:50:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:50:41 INFO - Running post-action listener: _resource_record_post_action 22:50:41 INFO - ##### 22:50:41 INFO - ##### Finished clobber step (success) 22:50:41 INFO - ##### 22:50:41 INFO - ##### 22:50:41 INFO - ##### Running read-buildbot-config step. 22:50:41 INFO - ##### 22:50:41 INFO - Running pre-action listener: _resource_record_pre_action 22:50:41 INFO - Running main action method: read_buildbot_config 22:50:41 INFO - Using buildbot properties: 22:50:41 INFO - { 22:50:41 INFO - "project": "", 22:50:41 INFO - "product": "firefox", 22:50:41 INFO - "script_repo_revision": "production", 22:50:41 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:50:41 INFO - "repository": "", 22:50:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:50:41 INFO - "buildid": "20160606202128", 22:50:41 INFO - "pgo_build": "True", 22:50:41 INFO - "basedir": "/builds/slave/test", 22:50:41 INFO - "buildnumber": 557, 22:50:41 INFO - "slavename": "talos-linux64-ix-020", 22:50:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:50:41 INFO - "platform": "ubuntu64_hw", 22:50:41 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:50:41 INFO - "branch": "mozilla-aurora", 22:50:41 INFO - "repo_path": "releases/mozilla-aurora", 22:50:41 INFO - "revision": "e70892847544db94d801f80543fe8a7f6d836f90", 22:50:41 INFO - "stage_platform": "linux64-pgo", 22:50:41 INFO - "builduid": "7ec3cba5ae654a229f94a8ace0e91e74", 22:50:41 INFO - "slavebuilddir": "test" 22:50:41 INFO - } 22:50:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 22:50:41 INFO - Running post-action listener: _resource_record_post_action 22:50:41 INFO - ##### 22:50:41 INFO - ##### Finished read-buildbot-config step (success) 22:50:41 INFO - ##### 22:50:41 INFO - ##### 22:50:41 INFO - ##### Running download-and-extract step. 22:50:41 INFO - ##### 22:50:41 INFO - Running pre-action listener: _resource_record_pre_action 22:50:41 INFO - Running main action method: download_and_extract 22:50:41 INFO - mkdir: /builds/slave/test/build/tests 22:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:50:41 INFO - https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:50:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:50:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:50:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:50:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:50:42 INFO - Downloaded 1361 bytes. 22:50:42 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 22:50:42 INFO - Using the following test package requirements: 22:50:42 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 22:50:42 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:50:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:50:42 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:50:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:50:42 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:50:42 INFO - u'jsshell-linux-x86_64.zip'], 22:50:42 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:50:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:50:42 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 22:50:42 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:50:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:50:42 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:50:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:50:42 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:50:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:50:42 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 22:50:42 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:50:42 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:50:42 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:50:42 INFO - https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:50:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:50:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:50:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:50:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:50:43 INFO - Downloaded 24473779 bytes. 22:50:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:50:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:50:44 INFO - Return code: 0 22:50:44 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:50:44 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:50:44 INFO - https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:50:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:50:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:50:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 22:50:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:50:46 INFO - Downloaded 24473779 bytes. 22:50:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:50:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:50:47 INFO - Return code: 0 22:50:47 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:50:47 INFO - https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:50:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 22:50:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 22:50:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 22:50:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:50:48 INFO - Downloaded 10833369 bytes. 22:50:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:50:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:50:49 INFO - Return code: 0 22:50:49 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:50:49 INFO - https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:50:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:50:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:50:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:50:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:50:50 INFO - Downloaded 63553597 bytes. 22:50:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:50:50 INFO - mkdir: /builds/slave/test/properties 22:50:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:50:50 INFO - Writing to file /builds/slave/test/properties/build_url 22:50:50 INFO - Contents: 22:50:50 INFO - build_url:https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 22:50:51 INFO - Running post-action listener: _resource_record_post_action 22:50:51 INFO - Running post-action listener: set_extra_try_arguments 22:50:51 INFO - ##### 22:50:51 INFO - ##### Finished download-and-extract step (success) 22:50:51 INFO - ##### 22:50:51 INFO - ##### 22:50:51 INFO - ##### Running populate-webroot step. 22:50:51 INFO - ##### 22:50:51 INFO - Running pre-action listener: _resource_record_pre_action 22:50:51 INFO - Running main action method: populate_webroot 22:50:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:50:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:50:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:50:51 INFO - '--tppagecycles', 22:50:51 INFO - '7'], 22:50:51 INFO - 'tests': ['tsvgm']}}, 22:50:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:50:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:50:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:50:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:50:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:51 INFO - 'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:50:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:50:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:51 INFO - 'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['damp', 'tps']}, 22:50:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:51 INFO - 'tests': ['damp', 'tps']}, 22:50:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['dromaeo_dom']}, 22:50:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:50:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['a11yr', 22:50:51 INFO - 'ts_paint', 22:50:51 INFO - 'tpaint', 22:50:51 INFO - 'sessionrestore', 22:50:51 INFO - 'sessionrestore_no_auto_restore', 22:50:51 INFO - 'tabpaint']}, 22:50:51 INFO - 'other-e10s': {'tests': ['a11yr', 22:50:51 INFO - 'ts_paint', 22:50:51 INFO - 'tpaint', 22:50:51 INFO - 'sessionrestore', 22:50:51 INFO - 'sessionrestore_no_auto_restore', 22:50:51 INFO - 'tabpaint']}, 22:50:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:50:51 INFO - 'ts_paint', 22:50:51 INFO - 'tpaint', 22:50:51 INFO - 'sessionrestore', 22:50:51 INFO - 'sessionrestore_no_auto_restore', 22:50:51 INFO - 'tabpaint']}, 22:50:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:50:51 INFO - 'ts_paint', 22:50:51 INFO - 'tpaint', 22:50:51 INFO - 'sessionrestore', 22:50:51 INFO - 'sessionrestore_no_auto_restore', 22:50:51 INFO - 'tabpaint']}, 22:50:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['a11yr', 22:50:51 INFO - 'ts_paint', 22:50:51 INFO - 'tpaint', 22:50:51 INFO - 'sessionrestore', 22:50:51 INFO - 'sessionrestore_no_auto_restore', 22:50:51 INFO - 'tabpaint']}, 22:50:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['a11yr', 22:50:51 INFO - 'ts_paint', 22:50:51 INFO - 'tpaint', 22:50:51 INFO - 'sessionrestore', 22:50:51 INFO - 'sessionrestore_no_auto_restore', 22:50:51 INFO - 'tabpaint']}, 22:50:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['tsvgx', 22:50:51 INFO - 'tsvgr_opacity', 22:50:51 INFO - 'tart', 22:50:51 INFO - 'tscrollx', 22:50:51 INFO - 'cart']}, 22:50:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:50:51 INFO - 'tsvgr_opacity', 22:50:51 INFO - 'tart', 22:50:51 INFO - 'tscrollx', 22:50:51 INFO - 'cart']}, 22:50:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:51 INFO - 'talos_options': ['--disable-e10s'], 22:50:51 INFO - 'tests': ['tp5o']}, 22:50:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:51 INFO - 'tests': ['tp5o']}, 22:50:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:51 INFO - 'talos_options': ['--disable-e10s', 22:50:51 INFO - '--xperf_path', 22:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:50:51 INFO - 'tests': ['tp5n']}, 22:50:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:50:51 INFO - 'talos_options': ['--xperf_path', 22:50:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:50:51 INFO - 'tests': ['tp5n']}}, 22:50:51 INFO - 'talos.zip': {'path': '', 22:50:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:50:51 INFO - Running post-action listener: _resource_record_post_action 22:50:51 INFO - ##### 22:50:51 INFO - ##### Finished populate-webroot step (success) 22:50:51 INFO - ##### 22:50:51 INFO - ##### 22:50:51 INFO - ##### Running create-virtualenv step. 22:50:51 INFO - ##### 22:50:51 INFO - Running pre-action listener: _resource_record_pre_action 22:50:51 INFO - Running main action method: create_virtualenv 22:50:51 INFO - Creating virtualenv /builds/slave/test/build/venv 22:50:51 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:50:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:50:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:50:51 INFO - Using real prefix '/usr' 22:50:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:50:51 INFO - Installing distribute.............................................................................................................................................................................................done. 22:50:53 INFO - Installing pip.................done. 22:50:53 INFO - Return code: 0 22:50:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:50:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:50:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:50:53 INFO - Using env: {'DISPLAY': ':0', 22:50:53 INFO - 'HOME': '/home/cltbld', 22:50:53 INFO - 'LANG': 'en_US.UTF-8', 22:50:53 INFO - 'LANGUAGE': 'en_US:en', 22:50:53 INFO - 'LOGNAME': 'cltbld', 22:50:53 INFO - 'MAIL': '/var/mail/cltbld', 22:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:53 INFO - 'MOZ_NO_REMOTE': '1', 22:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:53 INFO - 'NO_EM_RESTART': '1', 22:50:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:53 INFO - 'PWD': '/builds/slave/test', 22:50:53 INFO - 'SHELL': '/bin/bash', 22:50:53 INFO - 'SHLVL': '1', 22:50:53 INFO - 'TERM': 'linux', 22:50:53 INFO - 'TMOUT': '86400', 22:50:53 INFO - 'USER': 'cltbld', 22:50:53 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:53 INFO - '_': '/tools/buildbot/bin/python'} 22:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:54 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:50:54 INFO - Cleaning up... 22:50:54 INFO - Return code: 0 22:50:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:50:54 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:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:54 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:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:54 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 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:50:54 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:50:54 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:50:54 INFO - Using env: {'DISPLAY': ':0', 22:50:54 INFO - 'HOME': '/home/cltbld', 22:50:54 INFO - 'LANG': 'en_US.UTF-8', 22:50:54 INFO - 'LANGUAGE': 'en_US:en', 22:50:54 INFO - 'LOGNAME': 'cltbld', 22:50:54 INFO - 'MAIL': '/var/mail/cltbld', 22:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:54 INFO - 'MOZ_NO_REMOTE': '1', 22:50:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:54 INFO - 'NO_EM_RESTART': '1', 22:50:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:54 INFO - 'PWD': '/builds/slave/test', 22:50:54 INFO - 'SHELL': '/bin/bash', 22:50:54 INFO - 'SHLVL': '1', 22:50:54 INFO - 'TERM': 'linux', 22:50:54 INFO - 'TMOUT': '86400', 22:50:54 INFO - 'USER': 'cltbld', 22:50:54 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:54 INFO - '_': '/tools/buildbot/bin/python'} 22:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:54 INFO - Downloading/unpacking psutil>=3.1.1 22:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:50:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:50:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:50:56 INFO - Installing collected packages: psutil 22:50:56 INFO - Running setup.py install for psutil 22:50:56 INFO - building 'psutil._psutil_linux' extension 22:50:56 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:50:56 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:50:56 INFO - building 'psutil._psutil_posix' extension 22:50:56 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:50:56 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:50:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:50:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:50:57 INFO - Successfully installed psutil 22:50:57 INFO - Cleaning up... 22:50:57 INFO - Return code: 0 22:50:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:50:57 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:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:50:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:57 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:50:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:50:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:50:57 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 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:50:57 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:50:57 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:50:57 INFO - Using env: {'DISPLAY': ':0', 22:50:57 INFO - 'HOME': '/home/cltbld', 22:50:57 INFO - 'LANG': 'en_US.UTF-8', 22:50:57 INFO - 'LANGUAGE': 'en_US:en', 22:50:57 INFO - 'LOGNAME': 'cltbld', 22:50:57 INFO - 'MAIL': '/var/mail/cltbld', 22:50:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:57 INFO - 'MOZ_NO_REMOTE': '1', 22:50:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:57 INFO - 'NO_EM_RESTART': '1', 22:50:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:50:57 INFO - 'PWD': '/builds/slave/test', 22:50:57 INFO - 'SHELL': '/bin/bash', 22:50:57 INFO - 'SHLVL': '1', 22:50:57 INFO - 'TERM': 'linux', 22:50:57 INFO - 'TMOUT': '86400', 22:50:57 INFO - 'USER': 'cltbld', 22:50:57 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:50:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:57 INFO - '_': '/tools/buildbot/bin/python'} 22:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:50:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:50:57 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:50:57 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:50:57 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:50:57 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:50:57 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:50:57 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:51:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:51:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:51:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:51:00 INFO - Installing collected packages: mozsystemmonitor 22:51:00 INFO - Running setup.py install for mozsystemmonitor 22:51:00 INFO - Successfully installed mozsystemmonitor 22:51:00 INFO - Cleaning up... 22:51:00 INFO - Return code: 0 22:51:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:51:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:51:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:00 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 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:51:00 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:51:00 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:51:00 INFO - Using env: {'DISPLAY': ':0', 22:51:00 INFO - 'HOME': '/home/cltbld', 22:51:00 INFO - 'LANG': 'en_US.UTF-8', 22:51:00 INFO - 'LANGUAGE': 'en_US:en', 22:51:00 INFO - 'LOGNAME': 'cltbld', 22:51:00 INFO - 'MAIL': '/var/mail/cltbld', 22:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:51:00 INFO - 'MOZ_NO_REMOTE': '1', 22:51:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:51:00 INFO - 'NO_EM_RESTART': '1', 22:51:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:51:00 INFO - 'PWD': '/builds/slave/test', 22:51:00 INFO - 'SHELL': '/bin/bash', 22:51:00 INFO - 'SHLVL': '1', 22:51:00 INFO - 'TERM': 'linux', 22:51:00 INFO - 'TMOUT': '86400', 22:51:00 INFO - 'USER': 'cltbld', 22:51:00 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:51:00 INFO - '_': '/tools/buildbot/bin/python'} 22:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:51:00 INFO - Downloading/unpacking jsonschema==2.5.1 22:51:00 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:51:00 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:51:00 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:51:00 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:51:00 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:51:00 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:51:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:51:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 INFO - Downloading functools32-3.2.3-2.tar.gz 22:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:51:02 INFO - warning: no files found matching '*.txt' 22:51:02 INFO - no previously-included directories found matching 'build' 22:51:02 INFO - no previously-included directories found matching 'dist' 22:51:02 INFO - no previously-included directories found matching '.git*' 22:51:02 INFO - Installing collected packages: jsonschema, functools32 22:51:02 INFO - Running setup.py install for functools32 22:51:02 INFO - warning: no files found matching '*.txt' 22:51:02 INFO - no previously-included directories found matching 'build' 22:51:02 INFO - no previously-included directories found matching 'dist' 22:51:02 INFO - no previously-included directories found matching '.git*' 22:51:02 INFO - Successfully installed jsonschema functools32 22:51:02 INFO - Cleaning up... 22:51:02 INFO - Return code: 0 22:51:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:51:02 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:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:02 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:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:02 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 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:51:02 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:51:02 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:51:02 INFO - Using env: {'DISPLAY': ':0', 22:51:02 INFO - 'HOME': '/home/cltbld', 22:51:02 INFO - 'LANG': 'en_US.UTF-8', 22:51:02 INFO - 'LANGUAGE': 'en_US:en', 22:51:02 INFO - 'LOGNAME': 'cltbld', 22:51:02 INFO - 'MAIL': '/var/mail/cltbld', 22:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:51:02 INFO - 'MOZ_NO_REMOTE': '1', 22:51:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:51:02 INFO - 'NO_EM_RESTART': '1', 22:51:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:51:02 INFO - 'PWD': '/builds/slave/test', 22:51:02 INFO - 'SHELL': '/bin/bash', 22:51:02 INFO - 'SHLVL': '1', 22:51:02 INFO - 'TERM': 'linux', 22:51:02 INFO - 'TMOUT': '86400', 22:51:02 INFO - 'USER': 'cltbld', 22:51:02 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:51:02 INFO - '_': '/tools/buildbot/bin/python'} 22:51:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:51:03 INFO - Downloading/unpacking blobuploader==1.2.4 22:51:03 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:51:03 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:51:03 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:51:03 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:51:03 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:51:03 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:51:05 INFO - Downloading blobuploader-1.2.4.tar.gz 22:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:51:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:51:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 INFO - Downloading docopt-0.6.1.tar.gz 22:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:51:05 INFO - Installing collected packages: blobuploader, requests, docopt 22:51:05 INFO - Running setup.py install for blobuploader 22:51:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:51:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:51:05 INFO - Running setup.py install for requests 22:51:06 INFO - Running setup.py install for docopt 22:51:06 INFO - Successfully installed blobuploader requests docopt 22:51:06 INFO - Cleaning up... 22:51:06 INFO - Return code: 0 22:51:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:51:06 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:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:51:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:06 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:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:51:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:06 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 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:51:06 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:51:06 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:51:06 INFO - Using env: {'DISPLAY': ':0', 22:51:06 INFO - 'HOME': '/home/cltbld', 22:51:06 INFO - 'LANG': 'en_US.UTF-8', 22:51:06 INFO - 'LANGUAGE': 'en_US:en', 22:51:06 INFO - 'LOGNAME': 'cltbld', 22:51:06 INFO - 'MAIL': '/var/mail/cltbld', 22:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:51:06 INFO - 'MOZ_NO_REMOTE': '1', 22:51:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:51:06 INFO - 'NO_EM_RESTART': '1', 22:51:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:51:06 INFO - 'PWD': '/builds/slave/test', 22:51:06 INFO - 'SHELL': '/bin/bash', 22:51:06 INFO - 'SHLVL': '1', 22:51:06 INFO - 'TERM': 'linux', 22:51:06 INFO - 'TMOUT': '86400', 22:51:06 INFO - 'USER': 'cltbld', 22:51:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:51:06 INFO - '_': '/tools/buildbot/bin/python'} 22:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:51:06 INFO - Running setup.py (path:/tmp/pip-wmq7JR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:51:06 INFO - Running setup.py (path:/tmp/pip-fT9A_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:51:06 INFO - Running setup.py (path:/tmp/pip-ViX2iT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:51:06 INFO - Running setup.py (path:/tmp/pip-hYhNAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:51:06 INFO - Running setup.py (path:/tmp/pip-_jg4s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:51:07 INFO - Running setup.py (path:/tmp/pip-z81q1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:51:07 INFO - Running setup.py (path:/tmp/pip-mmaIo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:51:07 INFO - Running setup.py (path:/tmp/pip-ceZ7o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:51:07 INFO - Running setup.py (path:/tmp/pip-cQGlP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:51:07 INFO - Running setup.py (path:/tmp/pip-85KWPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:51:07 INFO - Running setup.py (path:/tmp/pip-Ufhk20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:51:07 INFO - Running setup.py (path:/tmp/pip-6hv3pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:51:07 INFO - Running setup.py (path:/tmp/pip-Z2wIE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:51:07 INFO - Running setup.py (path:/tmp/pip-xxjURp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:51:07 INFO - Running setup.py (path:/tmp/pip-5UobK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:51:07 INFO - Running setup.py (path:/tmp/pip-KOrGDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:51:07 INFO - Running setup.py (path:/tmp/pip-YBbpXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:51:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:51:07 INFO - Running setup.py install for manifestparser 22:51:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:51:07 INFO - Running setup.py install for mozcrash 22:51:08 INFO - Running setup.py install for mozdebug 22:51:08 INFO - Running setup.py install for mozdevice 22:51:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Running setup.py install for mozfile 22:51:08 INFO - Running setup.py install for mozhttpd 22:51:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Running setup.py install for mozinfo 22:51:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Running setup.py install for mozInstall 22:51:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Running setup.py install for mozleak 22:51:08 INFO - Running setup.py install for mozlog 22:51:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:51:08 INFO - Running setup.py install for moznetwork 22:51:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:51:09 INFO - Running setup.py install for mozprocess 22:51:09 INFO - Running setup.py install for mozprofile 22:51:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:51:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:51:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:51:09 INFO - Running setup.py install for mozrunner 22:51:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:51:09 INFO - Running setup.py install for mozscreenshot 22:51:09 INFO - Running setup.py install for moztest 22:51:09 INFO - Running setup.py install for mozversion 22:51:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:51:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:51:09 INFO - Cleaning up... 22:51:09 INFO - Return code: 0 22:51:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:51:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:51:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:09 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 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:51:09 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:51:09 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:51:09 INFO - Using env: {'DISPLAY': ':0', 22:51:09 INFO - 'HOME': '/home/cltbld', 22:51:09 INFO - 'LANG': 'en_US.UTF-8', 22:51:09 INFO - 'LANGUAGE': 'en_US:en', 22:51:09 INFO - 'LOGNAME': 'cltbld', 22:51:09 INFO - 'MAIL': '/var/mail/cltbld', 22:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:51:09 INFO - 'MOZ_NO_REMOTE': '1', 22:51:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:51:09 INFO - 'NO_EM_RESTART': '1', 22:51:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:51:09 INFO - 'PWD': '/builds/slave/test', 22:51:09 INFO - 'SHELL': '/bin/bash', 22:51:09 INFO - 'SHLVL': '1', 22:51:09 INFO - 'TERM': 'linux', 22:51:09 INFO - 'TMOUT': '86400', 22:51:09 INFO - 'USER': 'cltbld', 22:51:09 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:51:09 INFO - '_': '/tools/buildbot/bin/python'} 22:51:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:51:09 INFO - Running setup.py (path:/tmp/pip-Fkkszd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:51:09 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:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:51:09 INFO - Running setup.py (path:/tmp/pip-jWoS3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:51:10 INFO - Running setup.py (path:/tmp/pip-D1xnnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:51:10 INFO - Running setup.py (path:/tmp/pip-xT1ccs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:51:10 INFO - Running setup.py (path:/tmp/pip-fZgEOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:51:10 INFO - Running setup.py (path:/tmp/pip-WfyOTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:51:10 INFO - Running setup.py (path:/tmp/pip-mmQ6pH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:51:10 INFO - Running setup.py (path:/tmp/pip-uXTdPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:51:10 INFO - Running setup.py (path:/tmp/pip-cI6XON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:51:10 INFO - Running setup.py (path:/tmp/pip-vYe3r2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:51:10 INFO - Running setup.py (path:/tmp/pip-r2swu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:51:10 INFO - Running setup.py (path:/tmp/pip-9Eu5Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:51:10 INFO - Running setup.py (path:/tmp/pip-zeIz4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:51:10 INFO - Running setup.py (path:/tmp/pip-9Sc2mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:51:10 INFO - Running setup.py (path:/tmp/pip-1d6Spk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:51:10 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:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:51:10 INFO - Running setup.py (path:/tmp/pip-__iEWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:51:11 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:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:51:11 INFO - Running setup.py (path:/tmp/pip-TvUqkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:51:11 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:51:11 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:51:11 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:51:11 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:51:11 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:51:11 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:51:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:51:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:51:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:51:13 INFO - Downloading blessings-1.6.tar.gz 22:51:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:51:13 INFO - Installing collected packages: blessings 22:51:13 INFO - Running setup.py install for blessings 22:51:13 INFO - Successfully installed blessings 22:51:13 INFO - Cleaning up... 22:51:13 INFO - Return code: 0 22:51:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:51:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:51:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:51:13 INFO - Reading from file tmpfile_stdout 22:51:13 INFO - Current package versions: 22:51:13 INFO - argparse == 1.2.1 22:51:13 INFO - blessings == 1.6 22:51:13 INFO - blobuploader == 1.2.4 22:51:13 INFO - docopt == 0.6.1 22:51:13 INFO - functools32 == 3.2.3-2 22:51:13 INFO - jsonschema == 2.5.1 22:51:13 INFO - manifestparser == 1.1 22:51:13 INFO - mozInstall == 1.12 22:51:13 INFO - mozcrash == 0.17 22:51:13 INFO - mozdebug == 0.1 22:51:13 INFO - mozdevice == 0.48 22:51:13 INFO - mozfile == 1.2 22:51:13 INFO - mozhttpd == 0.7 22:51:13 INFO - mozinfo == 0.9 22:51:13 INFO - mozleak == 0.1 22:51:13 INFO - mozlog == 3.2 22:51:13 INFO - moznetwork == 0.27 22:51:13 INFO - mozprocess == 0.22 22:51:13 INFO - mozprofile == 0.28 22:51:13 INFO - mozrunner == 6.11 22:51:13 INFO - mozscreenshot == 0.1 22:51:13 INFO - mozsystemmonitor == 0.3 22:51:13 INFO - moztest == 0.7 22:51:13 INFO - mozversion == 1.4 22:51:13 INFO - psutil == 3.1.1 22:51:13 INFO - requests == 1.2.3 22:51:13 INFO - wsgiref == 0.1.2 22:51:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:13 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 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:51:13 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:51:13 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:51:13 INFO - Using env: {'DISPLAY': ':0', 22:51:13 INFO - 'HOME': '/home/cltbld', 22:51:13 INFO - 'LANG': 'en_US.UTF-8', 22:51:13 INFO - 'LANGUAGE': 'en_US:en', 22:51:13 INFO - 'LOGNAME': 'cltbld', 22:51:13 INFO - 'MAIL': '/var/mail/cltbld', 22:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:51:13 INFO - 'MOZ_NO_REMOTE': '1', 22:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:51:13 INFO - 'NO_EM_RESTART': '1', 22:51:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:51:13 INFO - 'PWD': '/builds/slave/test', 22:51:13 INFO - 'SHELL': '/bin/bash', 22:51:13 INFO - 'SHLVL': '1', 22:51:13 INFO - 'TERM': 'linux', 22:51:13 INFO - 'TMOUT': '86400', 22:51:13 INFO - 'USER': 'cltbld', 22:51:13 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:51:13 INFO - '_': '/tools/buildbot/bin/python'} 22:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:51:13 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:51:13 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:51:13 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:51:13 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:51:13 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:51:13 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:51:13 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:51:13 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:51:13 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:51:13 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:51:13 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:51:13 INFO - Cleaning up... 22:51:13 INFO - Return code: 0 22:51:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:51:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:51:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:51:13 INFO - Using env: {'DISPLAY': ':0', 22:51:13 INFO - 'HOME': '/home/cltbld', 22:51:13 INFO - 'LANG': 'en_US.UTF-8', 22:51:13 INFO - 'LANGUAGE': 'en_US:en', 22:51:13 INFO - 'LOGNAME': 'cltbld', 22:51:13 INFO - 'MAIL': '/var/mail/cltbld', 22:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:51:13 INFO - 'MOZ_NO_REMOTE': '1', 22:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:51:13 INFO - 'NO_EM_RESTART': '1', 22:51:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:51:13 INFO - 'PWD': '/builds/slave/test', 22:51:13 INFO - 'SHELL': '/bin/bash', 22:51:13 INFO - 'SHLVL': '1', 22:51:13 INFO - 'TERM': 'linux', 22:51:13 INFO - 'TMOUT': '86400', 22:51:13 INFO - 'USER': 'cltbld', 22:51:13 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:51:13 INFO - '_': '/tools/buildbot/bin/python'} 22:51:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:51:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:51:13 INFO - Cleaning up... 22:51:13 INFO - Return code: 0 22:51:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:51:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:51:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:51:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f47fb41bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13188a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, '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': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', '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:51:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:51:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:51:13 INFO - Using env: {'DISPLAY': ':0', 22:51:13 INFO - 'HOME': '/home/cltbld', 22:51:13 INFO - 'LANG': 'en_US.UTF-8', 22:51:13 INFO - 'LANGUAGE': 'en_US:en', 22:51:13 INFO - 'LOGNAME': 'cltbld', 22:51:13 INFO - 'MAIL': '/var/mail/cltbld', 22:51:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:51:13 INFO - 'MOZ_NO_REMOTE': '1', 22:51:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:51:13 INFO - 'NO_EM_RESTART': '1', 22:51:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:51:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:51:13 INFO - 'PWD': '/builds/slave/test', 22:51:13 INFO - 'SHELL': '/bin/bash', 22:51:13 INFO - 'SHLVL': '1', 22:51:13 INFO - 'TERM': 'linux', 22:51:13 INFO - 'TMOUT': '86400', 22:51:13 INFO - 'USER': 'cltbld', 22:51:13 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:51:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:51:13 INFO - '_': '/tools/buildbot/bin/python'} 22:51:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:51:14 INFO - Downloading/unpacking flake8 22:51:14 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:51:14 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:51:14 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:51:14 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:51:14 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:51:14 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:51:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:51:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:51:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:51:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:51:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:51:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:51:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:51:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:51:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:51:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:51:16 INFO - no previously-included directories found matching 'docs/_build' 22:51:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:51:16 INFO - Running setup.py install for pep8 22:51:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:51:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:51:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:51:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:51:16 INFO - no previously-included directories found matching 'docs/_build' 22:51:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:51:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:51:16 INFO - Cleaning up... 22:51:17 INFO - Return code: 0 22:51:17 INFO - Running post-action listener: _resource_record_post_action 22:51:17 INFO - Running post-action listener: _start_resource_monitoring 22:51:17 INFO - Starting resource monitoring. 22:51:17 INFO - ##### 22:51:17 INFO - ##### Finished create-virtualenv step (success) 22:51:17 INFO - ##### 22:51:17 INFO - ##### 22:51:17 INFO - ##### Running install step. 22:51:17 INFO - ##### 22:51:17 INFO - Running pre-action listener: _resource_record_pre_action 22:51:17 INFO - Running main action method: install 22:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:51:17 INFO - Reading from file tmpfile_stdout 22:51:17 INFO - Detecting whether we're running mozinstall >=1.0... 22:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:51:17 INFO - Reading from file tmpfile_stdout 22:51:17 INFO - Output received: 22:51:17 INFO - Usage: mozinstall [options] installer 22:51:17 INFO - Options: 22:51:17 INFO - -h, --help show this help message and exit 22:51:17 INFO - -d DEST, --destination=DEST 22:51:17 INFO - Directory to install application into. [default: 22:51:17 INFO - "/builds/slave/test"] 22:51:17 INFO - --app=APP Application being installed. [default: firefox] 22:51:17 INFO - mkdir: /builds/slave/test/build/application 22:51:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:51:33 INFO - Reading from file tmpfile_stdout 22:51:33 INFO - Output received: 22:51:33 INFO - /builds/slave/test/build/application/firefox/firefox 22:51:33 INFO - Running post-action listener: _resource_record_post_action 22:51:33 INFO - ##### 22:51:33 INFO - ##### Finished install step (success) 22:51:33 INFO - ##### 22:51:33 INFO - ##### 22:51:33 INFO - ##### Running run-tests step. 22:51:33 INFO - ##### 22:51:33 INFO - Running pre-action listener: _resource_record_pre_action 22:51:33 INFO - Running main action method: run_tests 22:51:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:51:33 INFO - Python 2.7.3 22:51:33 INFO - Return code: 0 22:51:33 INFO - grabbing minidump binary from tooltool 22:51:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:51:33 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 0x14772e0>, '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 0x13e3140>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e35d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:51:33 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:51:33 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:51:33 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:51:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:51:33 INFO - Return code: 0 22:51:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:51:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:51:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:51:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:51:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:51:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:51:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:51:34 INFO - Return code: 0 22:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:51:34 INFO - Using env: {'DISPLAY': ':0', 22:51:34 INFO - 'HOME': '/home/cltbld', 22:51:34 INFO - 'LANG': 'en_US.UTF-8', 22:51:34 INFO - 'LANGUAGE': 'en_US:en', 22:51:34 INFO - 'LOGNAME': 'cltbld', 22:51:34 INFO - 'MAIL': '/var/mail/cltbld', 22:51:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:51:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:51:34 INFO - 'MOZ_NO_REMOTE': '1', 22:51:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:51:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:51:34 INFO - 'NO_EM_RESTART': '1', 22:51:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:51:34 INFO - 'PWD': '/builds/slave/test', 22:51:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:51:34 INFO - 'SHELL': '/bin/bash', 22:51:34 INFO - 'SHLVL': '1', 22:51:34 INFO - 'TERM': 'linux', 22:51:34 INFO - 'TMOUT': '86400', 22:51:34 INFO - 'USER': 'cltbld', 22:51:34 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066', 22:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:51:34 INFO - '_': '/tools/buildbot/bin/python'} 22:51:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:51:34 INFO - mozversion application_buildid: 20160606202128 22:51:34 INFO - mozversion application_changeset: e70892847544db94d801f80543fe8a7f6d836f90 22:51:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:51:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:51:34 INFO - mozversion application_name: Firefox 22:51:34 INFO - mozversion application_remotingname: firefox-dev 22:51:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:51:34 INFO - mozversion application_vendor: Mozilla 22:51:34 INFO - mozversion application_version: 49.0a2 22:51:34 INFO - mozversion platform_buildid: 20160606202128 22:51:34 INFO - mozversion platform_changeset: e70892847544db94d801f80543fe8a7f6d836f90 22:51:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:51:34 INFO - mozversion platform_version: 49.0a2 22:51:34 INFO - using testdate: 1465278694 22:51:34 INFO - actual date: 1465278694 22:51:34 INFO - starting webserver on 'localhost:44591' 22:51:34 INFO - SUITE-START | Running 2 tests 22:51:34 INFO - TEST-START | dromaeo_css 22:51:34 INFO - operating with platform_type : linux_ 22:51:34 INFO - Initialising browser for dromaeo_css test... 22:51:34 INFO - TEST-INFO | started process 22152 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0em6sq/profile http://localhost:44591/getInfo.html) 22:51:38 INFO - PROCESS | 22152 | __metrics Screen width/height:1600/1200 22:51:38 INFO - PROCESS | 22152 | colorDepth:24 22:51:38 INFO - PROCESS | 22152 | Browser inner width/height: 1024/697 22:51:38 INFO - PROCESS | 22152 | __metrics 22:51:40 ERROR - PROCESS | 22152 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:51:40 INFO - PROCESS | 22152 | console.error: PushService: 22:51:40 INFO - PROCESS | 22152 | stateChangeProcessEnqueue: Error transitioning state 22:51:40 INFO - PROCESS | 22152 | UnknownError 22:51:40 INFO - TEST-INFO | 22152: exit 0 22:51:40 INFO - Browser initialized. 22:51:40 INFO - Running cycle 1/1 for dromaeo_css test... 22:51:40 INFO - TEST-INFO | started process 22244 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0em6sq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:51:52 INFO - PROCESS | 22244 | RSS: Main: 148701184 22:51:52 INFO - PROCESS | 22244 | 22:52:43 INFO - PROCESS | 22244 | 1465278763763 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:54:51 INFO - PROCESS | 22244 | Cycle 1(1): loaded http://localhost:44591/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44591/tests/dromaeo/cssquery-ext.html) 22:54:52 INFO - PROCESS | 22244 | RSS: Main: 262299648 22:54:52 INFO - PROCESS | 22244 | 22:57:55 INFO - PROCESS | 22244 | Cycle 1(1): loaded http://localhost:44591/tests/dromaeo/cssquery-ext.html (next: http://localhost:44591/tests/dromaeo/cssquery-jquery.html) 22:57:55 INFO - PROCESS | 22244 | RSS: Main: 228024320 22:57:55 INFO - PROCESS | 22244 | 23:00:57 INFO - PROCESS | 22244 | Cycle 1(1): loaded http://localhost:44591/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44591/tests/dromaeo/cssquery-mootools.html) 23:00:58 INFO - PROCESS | 22244 | RSS: Main: 224743424 23:00:58 INFO - PROCESS | 22244 | 23:04:00 INFO - PROCESS | 22244 | Cycle 1(1): loaded http://localhost:44591/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44591/tests/dromaeo/cssquery-prototype.html) 23:04:00 INFO - PROCESS | 22244 | RSS: Main: 222703616 23:04:00 INFO - PROCESS | 22244 | 23:07:03 INFO - PROCESS | 22244 | Cycle 1(1): loaded http://localhost:44591/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44591/tests/dromaeo/cssquery-yui.html) 23:07:04 INFO - PROCESS | 22244 | RSS: Main: 219545600 23:07:04 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | Cycle 1(1): loaded http://localhost:44591/tests/dromaeo/cssquery-yui.html (next: http://localhost:44591/tests/dromaeo/cssquery-dojo.html) 23:10:06 INFO - PROCESS | 22244 | RSS: Main: 262844416 23:10:06 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | __start_tp_report 23:10:06 INFO - PROCESS | 22244 | _x_x_mozilla_page_load 23:10:06 INFO - PROCESS | 22244 | _x_x_mozilla_page_load_details 23:10:06 INFO - PROCESS | 22244 | |i|pagename|runs| 23:10:06 INFO - PROCESS | 22244 | |0;dojo.html;3368.0737797105785;3389.949150762739;3403.251284717362;3426.5031570422307;3440.501127336535;5709.771609135635;5786.566007549435;5793.435772341465;5802.332731735852;5806.158107074471;5720.22776925115;5763.740631671574;5765.45228203321;5773.797917072903;5783.9710801445935;5021.84934451967;5154.381474223099;5211.973940130294;5239.973800130994;5264.552513036397;5036.244563315496;5126;5177.145770947808;5177.430482646906;5183.663061900983;5116.283720279164;5172.431032586413;5239.371275446952;5274.57803375731;5288.286081379003;5340.06548853949;5342.305500284977;5343.625946183769;5348.705821179834;5352;5057.721825299607;5074.2895994560795;5113.590912726973;5235.110031294689;5250.291210686585;5618.63478873876;5662.631928924607;5668.8866222675315;5673.489385955242;5714.771409143629;6260.311365749764;6266.467350275232;6270.31026587075;6270.749170033238;6280.685965701697;6117.705611546597;6176.382361763833;6178.814635560941;6238.220222472191;6323.74705011799;5845.269341332291;5852.385499522531;5870.706464676749;5874.412558744177;5879.764809407661;6077.618012484291;6379.202599675041;6389.552731308857;6393.968030159819;6403;6033.78881739137;6067.271927368744;6099.84750381244;6129.35641757609;6135.171751813537;6207.6275423474735;6218.689065546704;6221.471174950089;6223.1287619733275;6223.222097237845;5868.354481007086;6071.089336599457;6180.81457556274;6198.504119670415;6198.876022479525;6111.174991376108;6194.0708893665405;6195.101710251988;6204.286507051656;6205.844853878689;4623.9075218495445;4708.105459962596;4711.175544279738;4715.504871988392;4730.479647238801;4108.301588729863;4132.070284186034;4139.77231252284;4200.705950583491;4224.302990006676;5897.498712609477;5897.557683173779;5906.114082887601;5908.291005079332;5933.732982015819;6260.686965651699;6262.248530176408;6265.906011409833;6268.122462855204;6275.96862015687;5769.221155144086;5869.559783016291;5892.381299963527;5932.436418540232;5963.224780778471;5408.215808707715;5411.161270003077;5416.431274716177;5419.539339156216;5422.15956526739;6206.348333425016;6535.738570457129;6548.312427195171;6554.573952693059;6559.8032059038305;6174.166487524127;6233;6238.500919926304;6299.307076221704;6305.5901366411035;6465.224173099258;6465.935340646533;6470.28826829039;6474.579152355271;6550.410463058157;5916.171735957056;6061.212042434456;6135.018397056449;6191.071339299141;6207.689615519296;6216;6295.244570651368;6337.746490140527;6340.873182536416;6341.302456729848;6130.080487926668;6148.16999705052;6148.3236843948025;6150.661713605795;6154.753809847556;5809.186713860147;5963.850903727268;5997.160397544434;6125.540584456095;6137.35557766446;5938.2280303560265;5964.970175149097;5966.164736936919;5970.253718285215;5985.072313791369;6133.546581367489;6134.938650613437;6137.84655383619;6143.447089761943;6144.38556144382;5729.455701708331;5865.208196893364;5921.911171332519;5961.582689211714;6041.093835924471 23:10:06 INFO - PROCESS | 22244 | |1;ext.html;586.3110844757409;643.9001954697023;647.142536139615;661.8213082467736;678.1929503890373;34947.47578786317;37696.43455348168;46414;47741.283880741816;47775.283370749465;50739.74630126853;50815.49184508154;51190.23214651774;51268.743656281666;51339.486605133934;8409;9072.546372681372;9256.953715231415;9267.582958766838;9268.073192680711;10804.72988175294;11043.392613406259;11078.501467433944;11085.778284434307;11341.603043893465;10810.945945270263;11036.944815275952;11344.829827552552;11352.716182095472;11358.886411135907;8406.873896891551;10856.78286434267;11060.889391106106;11126.221164518487;11140.777184456265;10983.560657573767;11063.114950803996;11071.280366776215;11093.667189984313;11093.889061109367;45693.314600281024;46032.309515357294;46044.53955460436;46121.308180377324;46168.3074753879;3784.6404591563755;3910.432987216866;3922.823472943696;4123.525794533637;4550.613197878184;36245.637543624754;36949.44575831365;37025.07437314062;37029.629703702885;37041.25917481662;4161.750294982311;4257.425247591566;4286.61420472159;4306.676999225071;4314.374415709736;390122;391390;392178;393321;394379.99999999715;40593.797031014656;41484;41662.791686041375;43592.56407435885;71696.64151679313;42650;42746;42925.78537107295;42930.785346073695;42934;16764.916175419046;16885.662286754196;16932.915335423244;16949.830501695073;16954.57613559646;12027.398630068461;13739.725205495834;13960.58118256454;13963.79054314186;14252;9587.856182157273;9644.807103857884;9647.855282170773;9704.175145112604;9717.854232186522;3618.5115009473384;3676.0809797550614;3691.1325838427947;3697.1126929537254;3707.888763337104;1081.086481922774;1082.7780305037447;1130.8247221680654;1220.6704189868685;1242.136714983078;34310.485342719876;34433;34543.82728086394;34786;34789.65210347864;1167.4396289780782;1168.2581560709011;1175.048210949134;1209.7580483903255;1231.3535393918264;18318.725219121723;18359.35742249015;18454.35409760652;18488.168032438305;18570;610400;617354.913225431;620350;620476;621795;67630.66184669045;68093.3190668097;68234;68235;68273.65863170652;1302.5180683147296;1413.6395219219032;1432.8280606327307;1434.1466827237778;1441.452248145677;34213.486797698555;34930.65069349274;35306.82346588251;35338.46992295169;36026.27947441089;50199.74900125622;50251.74874125606;50364.496355037445;50626;50646;442160;445044;445713;445745;446610;28754.71245287604;32447.351052978334;33061.33877322392;33260.50109248315;33277.50083748697;50853.74573127259;53279.46720532745;54007.18989215086;54064;54679.453205467435;43631.56368436402;44155.77922110369;44279.335809962235;45258.77370613126;46043.3093503591;43419.56580434155;43789.562104379824;43989;44309.784510774974;44813;42609.786951066286;45527;46254.07491850211;47488.287675685584;47489.28766068443;37927.62072379241;40248.3962740565;40592.1881562373;41053.589464104974;41384;29710.257243569085;29826.85086574553;30350.696493034786;30572.541411879283;30589.388212236077 23:10:06 INFO - PROCESS | 22244 | |2;jquery.html;433.8264694122351;434.52202577165144;436.01330883448384;436.15386150867323;442.4513603132115;19156.42530724079;20561;20672.689909651366;20699.586008279864;20870.26954056608;18787.718184227248;20094.09576569054;20103.798962010376;20119.698204526943;20432.795672043274;5728.198052272674;5960.970195149018;5990.610610310316;5997;6004.669743164124;5872.119182122695;5896.7051647417675;5923.555733319997;5932.080527518242;5934.792282270121;5856.297244330686;5873.559483038789;5875.236219291507;5878.735456904449;5889.793857214999;5655.9253741788925;5880.647161170322;5882.76468941244;5885.087811389241;5885.146653735184;5085.186370180791;5275.60432967529;5292.100342941709;5295.894082118374;5296.894062118775;24445.633315500283;24514.14200502974;24528.632070518957;24750.25749227526;25119.87440062806;2403.435192729707;2439.5120975804734;2516.672832531759;2791.7208279172123;2984.5672377505357;14579.92710036443;15126.168060756654;15203.847961520354;15633.296501657453;15694.058356498646;1960.4216756056996;1990.0843871365548;2023.4435530229157;2032.5223572460459;2111.6621340585434;52972.735136324074;80411;80931.19068809296;81258.18741812566;81336.18663813405;11087.72280692973;11542.94228528852;11769;11782.764344713229;11789.174757767045;16408.83591164097;16535.25591348392;16547.586310342096;16556;16568.337266509203;8549.957250213709;8555.187257210555;8823.205911467998;8907.73276801697;8966.327525435618;8439.70461033861;8541.84685067508;8564.143585641386;8620.396572239883;8652.437591556529;7164.462665300019;7172.46206534512;7264.891026634605;7284.70861165558;7470.0662417197855;2021.444102871722;2042.3974927396453;2073.7822528634274;2098.360000199923;2098.3704888533684;1010.6311196413257;1014.8477728340661;1032.2039651007344;1044.7962647283707;1099.2250463423268;19979.300724474575;20578.897105514377;20626.690599641017;20949.37151885446;21077;1039.3919557059205;1044.253358848427;1075.6450371377427;1113.3097479562723;1141.241074685325;11213.439328033566;11860.347680877634;12056.75886482283;12132.818007729891;12206.694832629255;497555;503823;517612;521120;521975;61447.38552614506;75563.62218188983;81885;90333.54833225792;94047.52976235075;1073.2862646340252;1117.3686866920164;1131.0386171753944;1136.3749937534324;1144.2905398652888;18604.44186674402;18748.437546873614;18808.341708040152;18927.02964078107;19100.14049367754;41316.1736765269;42315.788421057696;43280;43514.12971740611;44443.77778111089;61532;63064.36935630769;63726;64229;65361.67319163374;9439.339246252972;9460.858087128836;9531.142197202285;9646.372985755903;9703.757406064904;12999.545015924397;13563.86436135626;13759.312034398039;14304.785428218376;14599;12042;12165.574204902787;12261.509539618315;12276.079294052803;12613.684657883627;6499.057636642755;10968;12268.44791984345;12396.566120185751;12509.687257818627;12077.637670869888;12752.298623575793;12829.743405131658;12925.870741292843;12994.220346779224;9385.108414700593;9481.76295592588;9494.810103797747;9519.571619277287;9665.157831451172;9585.376950498196;12354.44405001795;12601.495940162291;12604.180728252633;12818.102732808615 23:10:06 INFO - PROCESS | 22244 | |3;mootools.html;1474.9483768068105;1550.7596322570005;1592.0209071421075;1773.255232802223;1901.3250296144868;9397.953010234956;9895.307328487008;9901.108900198971;10198.388096714203;10217.489125543712;9490.288228382882;9749.512524373771;9767.072128147815;9769.023097690228;9769.511524423786;2185.278857976864;2361.055577768889;2433.4889673168655;2499.7750202481775;2532.645429639852;2546.2743118211315;2569.203546900467;2572.3954870605394;2576.755208255213;2578.4198555324997;2511.8744062796877;2536.226450932465;2540.212534114421;2540.27602133392;2543.1480454047914;2140.5397839464513;2463.864487453189;2553.885075171622;2554.5912653975274;2569.049451702882;2452.8773561321864;2464.8521088734556;2476.2571228631336;2497.8501289922665;2498.487809998946;9985.550650220757;9986.300958932878;10041.24690648197;10043.69868903934;10065.295429319951;5749;5896.174535565025;5905.43898329658;5906.822795316148;5917.14201440793;3606.4049431843714;3712.535933008374;3741.5510138783247;3749.718771092179;3826.081740382288;1166.0962753865738;1175.6355529785708;1181.1731787748524;1198.6284251881943;1233.272369302116;14235.644108897257;14263.500777472842;14282.285885705673;14286.357113930002;14298.356573954197;5077.111505486525;5112.4120726116225;5221.477852214824;5268.815591454358;5272.393674727378;11520.366379849022;11728.354940478184;11766.176367654181;11771.823422648667;11779.941100294614;5194.558462530727;5967.31375891778;5981.641101533922;5985.850353741104;5993.280806303272;5256.632035757537;5352.839414817562;5396.406395296514;5450.890982180414;5454.400015998237;4385.3641222022625;4425.800838962255;4463.62059224963;4501.932471012938;4511.977440112779;1341.1885809085559;1345.327336331834;1379.8620137986322;1384.439302082658;1397.3083323754604;926.4904302633524;1082.410086502858;1089.54239219528;1093.1309608860975;1120.6581992492368;6444.13004244421;6500.837479063061;6509.479241660656;6607.306232845578;6665.100211471487;477.97132172069786;493.6371766751435;494.16979474483213;495.5391485918131;502.29427654145985;4690.752786944179;4706.294055891575;4755.904881902343;4764.6664733469015;4849.830255941025;12100.152989290842;12626.747465050648;12629.115961882764;12667.809982850265;12679.873201267868;11892.167548271538;12317.630471085882;12413.255204687566;12838.229706217657;12895.355232238351;8537.573121344032;8883.911160888438;8887.644494220289;8910.019897811235;8925.687600933936;6640.236372817188;6856.348646878539;6911.3779759819845;7010.894836577353;7013.544119632207;11509.36698481558;11600.7819178985;11632.941835291107;11762;11851;11535.942320288681;11679.124065694938;11965.521379145084;11968.922796947969;11979.101567382308;8037.397195210499;8290.626921788655;8293.502389856629;8812.51531165792;9211.170994610517;9132.954335228282;9293.210077143518;9640;9690.030996900254;9941.105300522699;8707.869381959403;8857.867131993155;8884.955575222082;8976.551172441483;9039;8603.225709685908;8650.221480066824;8717.798507648104;8776.736697899074;8873.73378798637;8026.59867006659;8028.1570435105805;8110.79723006906;8115.066767321833;8119.5534245617055;5489.286392768914;5664.207011018543;5703.287089113861;5802.970985145047;5840.532757379485;5577;5808.5643576732755;5868.4424979626865;5972.820815375546;6065.54508411862 23:10:06 INFO - PROCESS | 22244 | |4;prototype.html;346.9063352894718;353.8814497143456;355.9697425718814;360.1698085911975;360.9584897736761;5632.7183640818;5659.971700141494;5687.146927960803;5718.59969802114;5730.369659337474;5276.92084618731;5334.572769389928;5517.641353312042;5559.2488366164625;5595.636283641571;4324.6972711910175;4353.151135528573;4377.781110944456;4379.189849877767;4390.560943905608;4365.257906155945;4391.704447188071;4401.141777353418;4403.031333106712;4462.152191083684;4182.22628813669;4192.287311157111;4353.129374125172;4364.2144414005625;4376.321670141133;4296.226679197744;4303.6103174761565;4318.093200427914;4323.502797178333;4368.453943257093;3843.250566139604;3858.9807050964564;3862.9806850965847;3885.708571857094;3898.3372826619543;5175.378954525481;5291.1798671206025;5293.86765330866;5342.519173274424;5383.946160538423;4939.777710003059;4965.131102057126;4967.931894642633;5013.498650134994;5037.370328708911;5716.71416429181;5860.443257890536;5870.182312489986;5887.705614719247;5889.499392551638;3991.640752332304;4227.450431443924;4250.893727656802;4252.723572967778;4259.957400425987;6042.667653279068;6043.244594425697;6102.115193296947;6126.172966649535;6139.171211886427;4333.783310834446;4336.609705126491;4337.6963612546815;4341.175176716414;4343.500497442835;4152.0485371875175;4346.478422589246;4358.956410435919;4374.234508960919;4390.780460976938;4917.237828136573;4947.579455746302;4966.602671786249;5047.848564543069;5071.290019397287;5782.421757824184;5988.161657367972;6007.429294217042;6013.879722405527;6033.849153771191;1439.9712005759827;1511.8866085043446;1517.924103794806;1518.7342215112312;1519.6475137127743;990.6037584965927;997.4813097189566;997.9051990060933;1008.2790804574618;1008.3042700536606;4961.5286547777905;4982.800687972513;4992.951480189128;5034.572061374751;5067.961067981055;5274.551663108602;5298.523132918056;5340.572754179656;5353.65396627108;5368.275282836845;4853.587445067138;5018.84943451697;5048.217526283432;5052.34319538465;5053.67151135175;2804.8445498813835;2813.71862813717;2835.5822088955524;2847.501687204772;2867;5329.934013197376;5362.544183744348;5450.182472629137;5457.253678822818;5482.629342664334;5345.54562862161;5371.409144994048;5766.279215098113;6195.752169913243;6216.222972128484;6219.626822390581;6269.655168965751;6300.810975670737;6314.526410519229;6315.652639104802;5858.297004359504;6080.300765412034;6132.846678833065;6134.447899689049;6140.478059364826;5999.700014999145;6015.135308054517;6020.939790602038;6023.909641355471;6026.668533230714;6256.280527739368;6260.530460215411;6285.968570157303;6301.401366870324;6309.432151106423;4192.098698779777;4305.82776688929;4307.590778876001;4338.349247612883;4343.326784348431;5825.854353641193;6049.78825741097;6135.631862088289;6136.8772624548155;6145.477634401126;5856.3558008618165;5888.4700376966275;5932.911006334822;5939.90111829313;5972.163897054329;5904.852378690567;5919.437653422918;5923.7926672566255;5955.166276721176;5959.523238141025;5591.916121258103;5698.287714035746;5754.741036653444;5758.510526605118;5826.999095465923;5875.794347197828;5881;5890.293164820249;5897.882042359214;5926.547995741071;5668.829935101953;5778.359633430911;5793.507551858139;5860.531157507475;5886.3223981165165 23:10:06 INFO - PROCESS | 22244 | |5;yui.html;785.1167436633787;809.1099790230745;819.6844214977232;841.9747407577775;853.3727710133052;16399.836001639997;16543.66912661749;16740.83259167408;17507;17683;15617.21913904306;15766.684666306666;15781.763273550878;15902.204889755494;15930.442434514795;744.7244519527783;863.4085651328828;878.6046279174382;887.6227603268617;888.6045709659204;875.1117615620151;876.5354362188051;877.1272583779148;878.9560521973908;879.3448880583974;931.9440833549975;934.6027938126269;935.0415823780611;935.6771913689801;936.5176933879058;779.5011192836561;800.9759707208764;913.4610579757974;929.8930622978343;938.0197693410403;692.2454524568194;692.7125243024138;693.3378623414627;693.5387967001938;693.743314973458;16258.105804180765;16273.267702953395;16325.59186020359;16345.673086538322;16383;14881.10713357202;14955.177465239407;15100.924495377563;15137.394504219817;15612.141332226724;13258.46966121354;13387.263700496469;13393.799093013613;13472.730545389037;13821.930890345584;4696.7651617419;4722.480527142012;4738.2892566115015;4761.880952976168;4852.344933434012;67769.66115169393;75836.70906889485;80230.59884700539;80322.59838700827;80650;11689.532418703327;11888;11989.940050299867;12031.217970831867;12065.396730163633;21126.78873211279;21269.46826329354;21387.46531336729;21407.892960535097;21534.56930861374;8932.017478077407;9090.36367454285;9320.580573874191;9460.905390946002;9788.853167202498;5115.667481613683;5136.948630513647;5181.4041385240425;5246.396664383568;5327.3074500314715;3676.8161591920298;3765.755225910307;3767.3030489359558;3768.2086761780333;3791.829367678461;561.4890449690762;698.2354322017383;705.7000774670745;707.0172460280214;707.7204504220804;662.4667142949875;712.5261700968798;726.785598248518;810.6433169405443;833.2251006563954;10400.635977740743;10441.321314114559;11150.442477876073;11211.103111751203;11281.71795705114;619.3775255867914;654.6693919570587;702.3327838553395;766.9693212271558;799.2447137455049;4963.875903102379;5088.160453525201;5286.7356632169;5311.3095297611835;5386.326709161355;74793;76143.61928190435;95623.56564651536;98305;99630.5018474903;93462.53268733613;95975.52012239894;96376;96702.51648741712;98692.50653746829;87140.5642971781;88085;88099.8845035644;88376.558117209;89914.55042724876;10179.733113001574;10290.279680422298;10608;10718.35689858611;10820.729481763019;52177.7391113042;52328.47671523236;52736.20895686491;52846.735766320926;52934.47065529449;67494.32505675076;68215.65892170508;75745.24254757381;77780;78470;13646.317684115953;13845.861541384456;13927.23400212998;13960;14771.556853294418;15570.455034073753;15642.843571564139;16086.758698619764;16524;16774.748378774573;14225.644358890697;14730.705385892437;14950.551483455514;15229.314680839167;15770.448034318744;14662.413503459742;15010.849891500946;15458.149801761012;15674.76487852706;15985.44050958211;14642.633934151732;14784.556463306119;14847;14987.700245995236;15048.322825473098;11542.192046556997;11542.769144616892;11851.940740296242;11967.10246731516;12070.879291206975;11493.482793274155;11563.88436115628;11859.466324015268;11988.220765650205;12136.757264854477 23:10:06 INFO - PROCESS | 22244 | __end_tp_report 23:10:06 INFO - PROCESS | 22244 | __start_cc_report 23:10:06 INFO - PROCESS | 22244 | _x_x_mozilla_cycle_collect,956 23:10:06 INFO - PROCESS | 22244 | __end_cc_report 23:10:06 INFO - PROCESS | 22244 | __startTimestamp1465279806891__endTimestamp 23:10:06 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | ------- Summary: start ------- 23:10:06 INFO - PROCESS | 22244 | Number of tests: 6 23:10:06 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | [#0] dojo.html Cycles:165 Average:5758.50 Median:5963.22 stddev:673.49 (11.3%) stddev-sans-first:648.93 23:10:06 INFO - PROCESS | 22244 | Values: 3368.1 3389.9 3403.3 3426.5 3440.5 5709.8 5786.6 5793.4 5802.3 5806.2 5720.2 5763.7 5765.5 5773.8 5784.0 5021.8 5154.4 5212.0 5240.0 5264.6 5036.2 5126.0 5177.1 5177.4 5183.7 5116.3 5172.4 5239.4 5274.6 5288.3 5340.1 5342.3 5343.6 5348.7 5352.0 5057.7 5074.3 5113.6 5235.1 5250.3 5618.6 5662.6 5668.9 5673.5 5714.8 6260.3 6266.5 6270.3 6270.7 6280.7 6117.7 6176.4 6178.8 6238.2 6323.7 5845.3 5852.4 5870.7 5874.4 5879.8 6077.6 6379.2 6389.6 6394.0 6403.0 6033.8 6067.3 6099.8 6129.4 6135.2 6207.6 6218.7 6221.5 6223.1 6223.2 5868.4 6071.1 6180.8 6198.5 6198.9 6111.2 6194.1 6195.1 6204.3 6205.8 4623.9 4708.1 4711.2 4715.5 4730.5 4108.3 4132.1 4139.8 4200.7 4224.3 5897.5 5897.6 5906.1 5908.3 5933.7 6260.7 6262.2 6265.9 6268.1 6276.0 5769.2 5869.6 5892.4 5932.4 5963.2 5408.2 5411.2 5416.4 5419.5 5422.2 6206.3 6535.7 6548.3 6554.6 6559.8 6174.2 6233.0 6238.5 6299.3 6305.6 6465.2 6465.9 6470.3 6474.6 6550.4 5916.2 6061.2 6135.0 6191.1 6207.7 6216.0 6295.2 6337.7 6340.9 6341.3 6130.1 6148.2 6148.3 6150.7 6154.8 5809.2 5963.9 5997.2 6125.5 6137.4 5938.2 5965.0 5966.2 5970.3 5985.1 6133.5 6134.9 6137.8 6143.4 6144.4 5729.5 5865.2 5921.9 5961.6 6041.1 23:10:06 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | [#1] ext.html Cycles:180 Average:64711.56 Median:40420.29 stddev:131499.79 (325.3%) stddev-sans-first:131780.54 23:10:06 INFO - PROCESS | 22244 | Values: 586.3 643.9 647.1 661.8 678.2 34947.5 37696.4 46414.0 47741.3 47775.3 50739.7 50815.5 51190.2 51268.7 51339.5 8409.0 9072.5 9257.0 9267.6 9268.1 10804.7 11043.4 11078.5 11085.8 11341.6 10810.9 11036.9 11344.8 11352.7 11358.9 8406.9 10856.8 11060.9 11126.2 11140.8 10983.6 11063.1 11071.3 11093.7 11093.9 45693.3 46032.3 46044.5 46121.3 46168.3 3784.6 3910.4 3922.8 4123.5 4550.6 36245.6 36949.4 37025.1 37029.6 37041.3 4161.8 4257.4 4286.6 4306.7 4314.4 390122.0 391390.0 392178.0 393321.0 394380.0 40593.8 41484.0 41662.8 43592.6 71696.6 42650.0 42746.0 42925.8 42930.8 42934.0 16764.9 16885.7 16932.9 16949.8 16954.6 12027.4 13739.7 13960.6 13963.8 14252.0 9587.9 9644.8 9647.9 9704.2 9717.9 3618.5 3676.1 3691.1 3697.1 3707.9 1081.1 1082.8 1130.8 1220.7 1242.1 34310.5 34433.0 34543.8 34786.0 34789.7 1167.4 1168.3 1175.0 1209.8 1231.4 18318.7 18359.4 18454.4 18488.2 18570.0 610400.0 617354.9 620350.0 620476.0 621795.0 67630.7 68093.3 68234.0 68235.0 68273.7 1302.5 1413.6 1432.8 1434.1 1441.5 34213.5 34930.7 35306.8 35338.5 36026.3 50199.7 50251.7 50364.5 50626.0 50646.0 442160.0 445044.0 445713.0 445745.0 446610.0 28754.7 32447.4 33061.3 33260.5 33277.5 50853.7 53279.5 54007.2 54064.0 54679.5 43631.6 44155.8 44279.3 45258.8 46043.3 43419.6 43789.6 43989.0 44309.8 44813.0 42609.8 45527.0 46254.1 47488.3 47489.3 37927.6 40248.4 40592.2 41053.6 41384.0 29710.3 29826.9 30350.7 30572.5 30589.4 23:10:06 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | [#2] jquery.html Cycles:180 Average:29981.78 Median:24479.89 stddev:84061.84 (343.4%) stddev-sans-first:84268.38 23:10:06 INFO - PROCESS | 22244 | Values: 433.8 434.5 436.0 436.2 442.5 19156.4 20561.0 20672.7 20699.6 20870.3 18787.7 20094.1 20103.8 20119.7 20432.8 5728.2 5961.0 5990.6 5997.0 6004.7 5872.1 5896.7 5923.6 5932.1 5934.8 5856.3 5873.6 5875.2 5878.7 5889.8 5655.9 5880.6 5882.8 5885.1 5885.1 5085.2 5275.6 5292.1 5295.9 5296.9 24445.6 24514.1 24528.6 24750.3 25119.9 2403.4 2439.5 2516.7 2791.7 2984.6 14579.9 15126.2 15203.8 15633.3 15694.1 1960.4 1990.1 2023.4 2032.5 2111.7 52972.7 80411.0 80931.2 81258.2 81336.2 11087.7 11542.9 11769.0 11782.8 11789.2 16408.8 16535.3 16547.6 16556.0 16568.3 8550.0 8555.2 8823.2 8907.7 8966.3 8439.7 8541.8 8564.1 8620.4 8652.4 7164.5 7172.5 7264.9 7284.7 7470.1 2021.4 2042.4 2073.8 2098.4 2098.4 1010.6 1014.8 1032.2 1044.8 1099.2 19979.3 20578.9 20626.7 20949.4 21077.0 1039.4 1044.3 1075.6 1113.3 1141.2 11213.4 11860.3 12056.8 12132.8 12206.7 497555.0 503823.0 517612.0 521120.0 521975.0 61447.4 75563.6 81885.0 90333.5 94047.5 1073.3 1117.4 1131.0 1136.4 1144.3 18604.4 18748.4 18808.3 18927.0 19100.1 41316.2 42315.8 43280.0 43514.1 44443.8 61532.0 63064.4 63726.0 64229.0 65361.7 9439.3 9460.9 9531.1 9646.4 9703.8 12999.5 13563.9 13759.3 14304.8 14599.0 12042.0 12165.6 12261.5 12276.1 12613.7 6499.1 10968.0 12268.4 12396.6 12509.7 12077.6 12752.3 12829.7 12925.9 12994.2 9385.1 9481.8 9494.8 9519.6 9665.2 9585.4 12354.4 12601.5 12604.2 12818.1 23:10:06 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | [#3] mootools.html Cycles:180 Average:6532.26 Median:4731.10 stddev:3833.62 (81.0%) stddev-sans-first:3825.54 23:10:06 INFO - PROCESS | 22244 | Values: 1474.9 1550.8 1592.0 1773.3 1901.3 9398.0 9895.3 9901.1 10198.4 10217.5 9490.3 9749.5 9767.1 9769.0 9769.5 2185.3 2361.1 2433.5 2499.8 2532.6 2546.3 2569.2 2572.4 2576.8 2578.4 2511.9 2536.2 2540.2 2540.3 2543.1 2140.5 2463.9 2553.9 2554.6 2569.0 2452.9 2464.9 2476.3 2497.9 2498.5 9985.6 9986.3 10041.2 10043.7 10065.3 5749.0 5896.2 5905.4 5906.8 5917.1 3606.4 3712.5 3741.6 3749.7 3826.1 1166.1 1175.6 1181.2 1198.6 1233.3 14235.6 14263.5 14282.3 14286.4 14298.4 5077.1 5112.4 5221.5 5268.8 5272.4 11520.4 11728.4 11766.2 11771.8 11779.9 5194.6 5967.3 5981.6 5985.9 5993.3 5256.6 5352.8 5396.4 5450.9 5454.4 4385.4 4425.8 4463.6 4501.9 4512.0 1341.2 1345.3 1379.9 1384.4 1397.3 926.5 1082.4 1089.5 1093.1 1120.7 6444.1 6500.8 6509.5 6607.3 6665.1 478.0 493.6 494.2 495.5 502.3 4690.8 4706.3 4755.9 4764.7 4849.8 12100.2 12626.7 12629.1 12667.8 12679.9 11892.2 12317.6 12413.3 12838.2 12895.4 8537.6 8883.9 8887.6 8910.0 8925.7 6640.2 6856.3 6911.4 7010.9 7013.5 11509.4 11600.8 11632.9 11762.0 11851.0 11535.9 11679.1 11965.5 11968.9 11979.1 8037.4 8290.6 8293.5 8812.5 9211.2 9133.0 9293.2 9640.0 9690.0 9941.1 8707.9 8857.9 8885.0 8976.6 9039.0 8603.2 8650.2 8717.8 8776.7 8873.7 8026.6 8028.2 8110.8 8115.1 8119.6 5489.3 5664.2 5703.3 5803.0 5840.5 5577.0 5808.6 5868.4 5972.8 6065.5 23:10:06 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | [#4] prototype.html Cycles:180 Average:4859.32 Median:5341.55 stddev:1436.20 (26.9%) stddev-sans-first:1399.72 23:10:06 INFO - PROCESS | 22244 | Values: 346.9 353.9 356.0 360.2 361.0 5632.7 5660.0 5687.1 5718.6 5730.4 5276.9 5334.6 5517.6 5559.2 5595.6 4324.7 4353.2 4377.8 4379.2 4390.6 4365.3 4391.7 4401.1 4403.0 4462.2 4182.2 4192.3 4353.1 4364.2 4376.3 4296.2 4303.6 4318.1 4323.5 4368.5 3843.3 3859.0 3863.0 3885.7 3898.3 5175.4 5291.2 5293.9 5342.5 5383.9 4939.8 4965.1 4967.9 5013.5 5037.4 5716.7 5860.4 5870.2 5887.7 5889.5 3991.6 4227.5 4250.9 4252.7 4260.0 6042.7 6043.2 6102.1 6126.2 6139.2 4333.8 4336.6 4337.7 4341.2 4343.5 4152.0 4346.5 4359.0 4374.2 4390.8 4917.2 4947.6 4966.6 5047.8 5071.3 5782.4 5988.2 6007.4 6013.9 6033.8 1440.0 1511.9 1517.9 1518.7 1519.6 990.6 997.5 997.9 1008.3 1008.3 4961.5 4982.8 4993.0 5034.6 5068.0 5274.6 5298.5 5340.6 5353.7 5368.3 4853.6 5018.8 5048.2 5052.3 5053.7 2804.8 2813.7 2835.6 2847.5 2867.0 5329.9 5362.5 5450.2 5457.3 5482.6 5345.5 5371.4 5766.3 6195.8 6216.2 6219.6 6269.7 6300.8 6314.5 6315.7 5858.3 6080.3 6132.8 6134.4 6140.5 5999.7 6015.1 6020.9 6023.9 6026.7 6256.3 6260.5 6286.0 6301.4 6309.4 4192.1 4305.8 4307.6 4338.3 4343.3 5825.9 6049.8 6135.6 6136.9 6145.5 5856.4 5888.5 5932.9 5939.9 5972.2 5904.9 5919.4 5923.8 5955.2 5959.5 5591.9 5698.3 5754.7 5758.5 5827.0 5875.8 5881.0 5890.3 5897.9 5926.5 5668.8 5778.4 5793.5 5860.5 5886.3 23:10:06 INFO - PROCESS | 22244 | 23:10:06 INFO - PROCESS | 22244 | [#5] yui.html Cycles:180 Average:20648.41 Median:5026.02 stddev:27732.07 (551.8%) stddev-sans-first:27769.75 23:10:06 INFO - PROCESS | 22244 | Values: 785.1 809.1 819.7 842.0 853.4 16399.8 16543.7 16740.8 17507.0 17683.0 15617.2 15766.7 15781.8 15902.2 15930.4 744.7 863.4 878.6 887.6 888.6 875.1 876.5 877.1 879.0 879.3 931.9 934.6 935.0 935.7 936.5 779.5 801.0 913.5 929.9 938.0 692.2 692.7 693.3 693.5 693.7 16258.1 16273.3 16325.6 16345.7 16383.0 14881.1 14955.2 15100.9 15137.4 15612.1 13258.5 13387.3 13393.8 13472.7 13821.9 4696.8 4722.5 4738.3 4761.9 4852.3 67769.7 75836.7 80230.6 80322.6 80650.0 11689.5 11888.0 11989.9 12031.2 12065.4 21126.8 21269.5 21387.5 21407.9 21534.6 8932.0 9090.4 9320.6 9460.9 9788.9 5115.7 5136.9 5181.4 5246.4 5327.3 3676.8 3765.8 3767.3 3768.2 3791.8 561.5 698.2 705.7 707.0 707.7 662.5 712.5 726.8 810.6 833.2 10400.6 10441.3 11150.4 11211.1 11281.7 619.4 654.7 702.3 767.0 799.2 4963.9 5088.2 5286.7 5311.3 5386.3 74793.0 76143.6 95623.6 98305.0 99630.5 93462.5 95975.5 96376.0 96702.5 98692.5 87140.6 88085.0 88099.9 88376.6 89914.6 10179.7 10290.3 10608.0 10718.4 10820.7 52177.7 52328.5 52736.2 52846.7 52934.5 67494.3 68215.7 75745.2 77780.0 78470.0 13646.3 13845.9 13927.2 13960.0 14771.6 15570.5 15642.8 16086.8 16524.0 16774.7 14225.6 14730.7 14950.6 15229.3 15770.4 14662.4 15010.8 15458.1 15674.8 15985.4 14642.6 14784.6 14847.0 14987.7 15048.3 11542.2 11542.8 11851.9 11967.1 12070.9 11493.5 11563.9 11859.5 11988.2 12136.8 23:10:06 INFO - PROCESS | 22244 | -------- Summary: end -------- 23:10:06 INFO - PROCESS | 22244 | 23:10:07 INFO - TEST-INFO | 22244: exit 0 23:10:07 INFO - TEST-OK | dromaeo_css | took 1113207ms 23:10:07 INFO - TEST-START | kraken 23:10:07 INFO - operating with platform_type : linux_ 23:10:07 INFO - Initialising browser for kraken test... 23:10:07 INFO - TEST-INFO | started process 22432 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaeKIE_/profile http://localhost:44591/getInfo.html) 23:10:11 INFO - PROCESS | 22432 | __metrics Screen width/height:1600/1200 23:10:11 INFO - PROCESS | 22432 | colorDepth:24 23:10:11 INFO - PROCESS | 22432 | Browser inner width/height: 1024/697 23:10:11 INFO - PROCESS | 22432 | __metrics 23:10:13 ERROR - PROCESS | 22432 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:10:13 INFO - PROCESS | 22432 | console.error: PushService: 23:10:13 INFO - PROCESS | 22432 | stateChangeProcessEnqueue: Error transitioning state 23:10:13 INFO - PROCESS | 22432 | UnknownError 23:10:13 INFO - TEST-INFO | 22432: exit 0 23:10:13 INFO - Browser initialized. 23:10:13 INFO - Running cycle 1/1 for kraken test... 23:10:13 INFO - TEST-INFO | started process 22521 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaeKIE_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:10:25 INFO - PROCESS | 22521 | RSS: Main: 145387520 23:10:25 INFO - PROCESS | 22521 | 23:10:28 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44591/tests/kraken/driver.html?testName=audio-beat-detection) 23:10:29 INFO - PROCESS | 22521 | RSS: Main: 194850816 23:10:29 INFO - PROCESS | 22521 | 23:10:31 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44591/tests/kraken/driver.html?testName=audio-dft) 23:10:32 INFO - PROCESS | 22521 | RSS: Main: 192860160 23:10:32 INFO - PROCESS | 22521 | 23:10:35 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44591/tests/kraken/driver.html?testName=audio-fft) 23:10:35 INFO - PROCESS | 22521 | RSS: Main: 278429696 23:10:35 INFO - PROCESS | 22521 | 23:10:37 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44591/tests/kraken/driver.html?testName=audio-oscillator) 23:10:38 INFO - PROCESS | 22521 | RSS: Main: 187686912 23:10:38 INFO - PROCESS | 22521 | 23:10:40 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44591/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:10:41 INFO - PROCESS | 22521 | RSS: Main: 188182528 23:10:41 INFO - PROCESS | 22521 | 23:10:44 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44591/tests/kraken/driver.html?testName=imaging-darkroom) 23:10:44 INFO - PROCESS | 22521 | RSS: Main: 222842880 23:10:44 INFO - PROCESS | 22521 | 23:10:48 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44591/tests/kraken/driver.html?testName=imaging-desaturate) 23:10:48 INFO - PROCESS | 22521 | RSS: Main: 223244288 23:10:48 INFO - PROCESS | 22521 | 23:10:51 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44591/tests/kraken/driver.html?testName=json-parse-financial) 23:10:52 INFO - PROCESS | 22521 | RSS: Main: 223682560 23:10:52 INFO - PROCESS | 22521 | 23:10:53 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44591/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:10:54 INFO - PROCESS | 22521 | RSS: Main: 201240576 23:10:54 INFO - PROCESS | 22521 | 23:10:55 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44591/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:10:56 INFO - PROCESS | 22521 | RSS: Main: 202264576 23:10:56 INFO - PROCESS | 22521 | 23:10:58 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44591/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:10:59 INFO - PROCESS | 22521 | RSS: Main: 209563648 23:10:59 INFO - PROCESS | 22521 | 23:11:01 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44591/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:11:02 INFO - PROCESS | 22521 | RSS: Main: 213901312 23:11:02 INFO - PROCESS | 22521 | 23:11:04 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44591/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:11:05 INFO - PROCESS | 22521 | RSS: Main: 210006016 23:11:05 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | Cycle 1(1): loaded http://localhost:44591/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44591/tests/kraken/driver.html?testName=ai-astar) 23:11:07 INFO - PROCESS | 22521 | RSS: Main: 252735488 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | __start_tp_report 23:11:07 INFO - PROCESS | 22521 | _x_x_mozilla_page_load 23:11:07 INFO - PROCESS | 22521 | _x_x_mozilla_page_load_details 23:11:07 INFO - PROCESS | 22521 | |i|pagename|runs| 23:11:07 INFO - PROCESS | 22521 | |0;ai-astar;110;105;111;111;109;112;110;110;109;108 23:11:07 INFO - PROCESS | 22521 | |1;audio-beat-detection;152;123;130;175;125;124;151;123;158;137 23:11:07 INFO - PROCESS | 22521 | |2;audio-dft;160;161;161;160;161;161;160;164;160;161 23:11:07 INFO - PROCESS | 22521 | |3;audio-fft;75;75;75;122;75;75;74;75;97;75 23:11:07 INFO - PROCESS | 22521 | |4;audio-oscillator;85;85;112;84;84;85;113;86;85;121 23:11:07 INFO - PROCESS | 22521 | |5;imaging-gaussian-blur;117;116;117;116;116;116;116;117;117;116 23:11:07 INFO - PROCESS | 22521 | |6;imaging-darkroom;145;145;145;146;146;146;145;145;145;145 23:11:07 INFO - PROCESS | 22521 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;95;94 23:11:07 INFO - PROCESS | 22521 | |8;json-parse-financial;50;50;53;52;53;51;52;52;52;52 23:11:07 INFO - PROCESS | 22521 | |9;json-stringify-tinderbox;60;60;59;59;60;59;60;59;60;59 23:11:07 INFO - PROCESS | 22521 | |10;stanford-crypto-aes;77;77;76;77;78;82;79;79;134;82 23:11:07 INFO - PROCESS | 22521 | |11;stanford-crypto-ccm;118;115;122;116;120;127;113;116;139;136 23:11:07 INFO - PROCESS | 22521 | |12;stanford-crypto-pbkdf2;160;156;156;154;150;153;162;155;151;153 23:11:07 INFO - PROCESS | 22521 | |13;stanford-crypto-sha256-iterative;70;62;61;61;63;60;61;64;60;60 23:11:07 INFO - PROCESS | 22521 | __end_tp_report 23:11:07 INFO - PROCESS | 22521 | __start_cc_report 23:11:07 INFO - PROCESS | 22521 | _x_x_mozilla_cycle_collect,1579 23:11:07 INFO - PROCESS | 22521 | __end_cc_report 23:11:07 INFO - PROCESS | 22521 | __startTimestamp1465279867417__endTimestamp 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | ------- Summary: start ------- 23:11:07 INFO - PROCESS | 22521 | Number of tests: 14 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:1.96 (1.8%) stddev-sans-first:2.07 23:11:07 INFO - PROCESS | 22521 | Values: 110.0 105.0 111.0 111.0 109.0 112.0 110.0 110.0 109.0 108.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:144.00 stddev:18.20 (12.6%) stddev-sans-first:18.76 23:11:07 INFO - PROCESS | 22521 | Values: 152.0 123.0 130.0 175.0 125.0 124.0 151.0 123.0 158.0 137.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 23:11:07 INFO - PROCESS | 22521 | Values: 160.0 161.0 161.0 160.0 161.0 161.0 160.0 164.0 160.0 161.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.00 stddev:15.75 (21.0%) stddev-sans-first:16.51 23:11:07 INFO - PROCESS | 22521 | Values: 75.0 75.0 75.0 122.0 75.0 75.0 74.0 75.0 97.0 75.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:85.00 stddev:14.91 (17.5%) stddev-sans-first:15.46 23:11:07 INFO - PROCESS | 22521 | Values: 85.0 85.0 112.0 84.0 84.0 85.0 113.0 86.0 85.0 121.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 23:11:07 INFO - PROCESS | 22521 | Values: 117.0 116.0 117.0 116.0 116.0 116.0 116.0 117.0 117.0 116.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 23:11:07 INFO - PROCESS | 22521 | Values: 145.0 145.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 23:11:07 INFO - PROCESS | 22521 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:1.06 (2.0%) stddev-sans-first:0.93 23:11:07 INFO - PROCESS | 22521 | Values: 50.0 50.0 53.0 52.0 53.0 51.0 52.0 52.0 52.0 52.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 23:11:07 INFO - PROCESS | 22521 | Values: 60.0 60.0 59.0 59.0 60.0 59.0 60.0 59.0 60.0 59.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:78.50 stddev:17.65 (22.5%) stddev-sans-first:18.54 23:11:07 INFO - PROCESS | 22521 | Values: 77.0 77.0 76.0 77.0 78.0 82.0 79.0 79.0 134.0 82.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#11] stanford-crypto-ccm Cycles:10 Average:122.20 Median:121.00 stddev:9.02 (7.5%) stddev-sans-first:9.43 23:11:07 INFO - PROCESS | 22521 | Values: 118.0 115.0 122.0 116.0 120.0 127.0 113.0 116.0 139.0 136.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.00 Median:155.50 stddev:3.74 (2.4%) stddev-sans-first:3.50 23:11:07 INFO - PROCESS | 22521 | Values: 160.0 156.0 156.0 154.0 150.0 153.0 162.0 155.0 151.0 153.0 23:11:07 INFO - PROCESS | 22521 | 23:11:07 INFO - PROCESS | 22521 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:61.50 stddev:3.05 (5.0%) stddev-sans-first:1.41 23:11:07 INFO - PROCESS | 22521 | Values: 70.0 62.0 61.0 61.0 63.0 60.0 61.0 64.0 60.0 60.0 23:11:07 INFO - PROCESS | 22521 | -------- Summary: end -------- 23:11:07 INFO - PROCESS | 22521 | 23:11:08 INFO - TEST-INFO | 22521: exit 0 23:11:08 INFO - TEST-OK | kraken | took 60743ms 23:11:08 INFO - SUITE-END | took 1173s 23:11:08 INFO - Completed test suite (00:19:34) 23:11:08 INFO - javascript benchmark 23:11:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.0737797105785, 3389.949150762739, 3403.251284717362, 3426.5031570422307, 3440.501127336535, 5709.771609135635, 5786.566007549435, 5793.435772341465, 5802.332731735852, 5806.158107074471, 5720.22776925115, 5763.740631671574, 5765.45228203321, 5773.797917072903, 5783.9710801445935, 5021.84934451967, 5154.381474223099, 5211.973940130294, 5239.973800130994, 5264.552513036397, 5036.244563315496, 5126.0, 5177.145770947808, 5177.430482646906, 5183.663061900983, 5116.283720279164, 5172.431032586413, 5239.371275446952, 5274.57803375731, 5288.286081379003, 5340.06548853949, 5342.305500284977, 5343.625946183769, 5348.705821179834, 5352.0, 5057.721825299607, 5074.2895994560795, 5113.590912726973, 5235.110031294689, 5250.291210686585, 5618.63478873876, 5662.631928924607, 5668.8866222675315, 5673.489385955242, 5714.771409143629, 6260.311365749764, 6266.467350275232, 6270.31026587075, 6270.749170033238, 6280.685965701697, 6117.705611546597, 6176.382361763833, 6178.814635560941, 6238.220222472191, 6323.74705011799, 5845.269341332291, 5852.385499522531, 5870.706464676749, 5874.412558744177, 5879.764809407661, 6077.618012484291, 6379.202599675041, 6389.552731308857, 6393.968030159819, 6403.0, 6033.78881739137, 6067.271927368744, 6099.84750381244, 6129.35641757609, 6135.171751813537, 6207.6275423474735, 6218.689065546704, 6221.471174950089, 6223.1287619733275, 6223.222097237845, 5868.354481007086, 6071.089336599457, 6180.81457556274, 6198.504119670415, 6198.876022479525, 6111.174991376108, 6194.0708893665405, 6195.101710251988, 6204.286507051656, 6205.844853878689, 4623.9075218495445, 4708.105459962596, 4711.175544279738, 4715.504871988392, 4730.479647238801, 4108.301588729863, 4132.070284186034, 4139.77231252284, 4200.705950583491, 4224.302990006676, 5897.498712609477, 5897.557683173779, 5906.114082887601, 5908.291005079332, 5933.732982015819, 6260.686965651699, 6262.248530176408, 6265.906011409833, 6268.122462855204, 6275.96862015687, 5769.221155144086, 5869.559783016291, 5892.381299963527, 5932.436418540232, 5963.224780778471, 5408.215808707715, 5411.161270003077, 5416.431274716177, 5419.539339156216, 5422.15956526739, 6206.348333425016, 6535.738570457129, 6548.312427195171, 6554.573952693059, 6559.8032059038305, 6174.166487524127, 6233.0, 6238.500919926304, 6299.307076221704, 6305.5901366411035, 6465.224173099258, 6465.935340646533, 6470.28826829039, 6474.579152355271, 6550.410463058157, 5916.171735957056, 6061.212042434456, 6135.018397056449, 6191.071339299141, 6207.689615519296, 6216.0, 6295.244570651368, 6337.746490140527, 6340.873182536416, 6341.302456729848, 6130.080487926668, 6148.16999705052, 6148.3236843948025, 6150.661713605795, 6154.753809847556, 5809.186713860147, 5963.850903727268, 5997.160397544434, 6125.540584456095, 6137.35557766446, 5938.2280303560265, 5964.970175149097, 5966.164736936919, 5970.253718285215, 5985.072313791369, 6133.546581367489, 6134.938650613437, 6137.84655383619, 6143.447089761943, 6144.38556144382, 5729.455701708331, 5865.208196893364, 5921.911171332519, 5961.582689211714, 6041.093835924471], "value": 5712.386422832929, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.3110844757409, 643.9001954697023, 647.142536139615, 661.8213082467736, 678.1929503890373, 34947.47578786317, 37696.43455348168, 46414.0, 47741.283880741816, 47775.283370749465, 50739.74630126853, 50815.49184508154, 51190.23214651774, 51268.743656281666, 51339.486605133934, 8409.0, 9072.546372681372, 9256.953715231415, 9267.582958766838, 9268.073192680711, 10804.72988175294, 11043.392613406259, 11078.501467433944, 11085.778284434307, 11341.603043893465, 10810.945945270263, 11036.944815275952, 11344.829827552552, 11352.716182095472, 11358.886411135907, 8406.873896891551, 10856.78286434267, 11060.889391106106, 11126.221164518487, 11140.777184456265, 10983.560657573767, 11063.114950803996, 11071.280366776215, 11093.667189984313, 11093.889061109367, 45693.314600281024, 46032.309515357294, 46044.53955460436, 46121.308180377324, 46168.3074753879, 3784.6404591563755, 3910.432987216866, 3922.823472943696, 4123.525794533637, 4550.613197878184, 36245.637543624754, 36949.44575831365, 37025.07437314062, 37029.629703702885, 37041.25917481662, 4161.750294982311, 4257.425247591566, 4286.61420472159, 4306.676999225071, 4314.374415709736, 390122.0, 391390.0, 392178.0, 393321.0, 394379.99999999715, 40593.797031014656, 41484.0, 41662.791686041375, 43592.56407435885, 71696.64151679313, 42650.0, 42746.0, 42925.78537107295, 42930.785346073695, 42934.0, 16764.916175419046, 16885.662286754196, 16932.915335423244, 16949.830501695073, 16954.57613559646, 12027.398630068461, 13739.725205495834, 13960.58118256454, 13963.79054314186, 14252.0, 9587.856182157273, 9644.807103857884, 9647.855282170773, 9704.175145112604, 9717.854232186522, 3618.5115009473384, 3676.0809797550614, 3691.1325838427947, 3697.1126929537254, 3707.888763337104, 1081.086481922774, 1082.7780305037447, 1130.8247221680654, 1220.6704189868685, 1242.136714983078, 34310.485342719876, 34433.0, 34543.82728086394, 34786.0, 34789.65210347864, 1167.4396289780782, 1168.2581560709011, 1175.048210949134, 1209.7580483903255, 1231.3535393918264, 18318.725219121723, 18359.35742249015, 18454.35409760652, 18488.168032438305, 18570.0, 610400.0, 617354.913225431, 620350.0, 620476.0, 621795.0, 67630.66184669045, 68093.3190668097, 68234.0, 68235.0, 68273.65863170652, 1302.5180683147296, 1413.6395219219032, 1432.8280606327307, 1434.1466827237778, 1441.452248145677, 34213.486797698555, 34930.65069349274, 35306.82346588251, 35338.46992295169, 36026.27947441089, 50199.74900125622, 50251.74874125606, 50364.496355037445, 50626.0, 50646.0, 442160.0, 445044.0, 445713.0, 445745.0, 446610.0, 28754.71245287604, 32447.351052978334, 33061.33877322392, 33260.50109248315, 33277.50083748697, 50853.74573127259, 53279.46720532745, 54007.18989215086, 54064.0, 54679.453205467435, 43631.56368436402, 44155.77922110369, 44279.335809962235, 45258.77370613126, 46043.3093503591, 43419.56580434155, 43789.562104379824, 43989.0, 44309.784510774974, 44813.0, 42609.786951066286, 45527.0, 46254.07491850211, 47488.287675685584, 47489.28766068443, 37927.62072379241, 40248.3962740565, 40592.1881562373, 41053.589464104974, 41384.0, 29710.257243569085, 29826.85086574553, 30350.696493034786, 30572.541411879283, 30589.388212236077], "value": 20781.164275860196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.8264694122351, 434.52202577165144, 436.01330883448384, 436.15386150867323, 442.4513603132115, 19156.42530724079, 20561.0, 20672.689909651366, 20699.586008279864, 20870.26954056608, 18787.718184227248, 20094.09576569054, 20103.798962010376, 20119.698204526943, 20432.795672043274, 5728.198052272674, 5960.970195149018, 5990.610610310316, 5997.0, 6004.669743164124, 5872.119182122695, 5896.7051647417675, 5923.555733319997, 5932.080527518242, 5934.792282270121, 5856.297244330686, 5873.559483038789, 5875.236219291507, 5878.735456904449, 5889.793857214999, 5655.9253741788925, 5880.647161170322, 5882.76468941244, 5885.087811389241, 5885.146653735184, 5085.186370180791, 5275.60432967529, 5292.100342941709, 5295.894082118374, 5296.894062118775, 24445.633315500283, 24514.14200502974, 24528.632070518957, 24750.25749227526, 25119.87440062806, 2403.435192729707, 2439.5120975804734, 2516.672832531759, 2791.7208279172123, 2984.5672377505357, 14579.92710036443, 15126.168060756654, 15203.847961520354, 15633.296501657453, 15694.058356498646, 1960.4216756056996, 1990.0843871365548, 2023.4435530229157, 2032.5223572460459, 2111.6621340585434, 52972.735136324074, 80411.0, 80931.19068809296, 81258.18741812566, 81336.18663813405, 11087.72280692973, 11542.94228528852, 11769.0, 11782.764344713229, 11789.174757767045, 16408.83591164097, 16535.25591348392, 16547.586310342096, 16556.0, 16568.337266509203, 8549.957250213709, 8555.187257210555, 8823.205911467998, 8907.73276801697, 8966.327525435618, 8439.70461033861, 8541.84685067508, 8564.143585641386, 8620.396572239883, 8652.437591556529, 7164.462665300019, 7172.46206534512, 7264.891026634605, 7284.70861165558, 7470.0662417197855, 2021.444102871722, 2042.3974927396453, 2073.7822528634274, 2098.360000199923, 2098.3704888533684, 1010.6311196413257, 1014.8477728340661, 1032.2039651007344, 1044.7962647283707, 1099.2250463423268, 19979.300724474575, 20578.897105514377, 20626.690599641017, 20949.37151885446, 21077.0, 1039.3919557059205, 1044.253358848427, 1075.6450371377427, 1113.3097479562723, 1141.241074685325, 11213.439328033566, 11860.347680877634, 12056.75886482283, 12132.818007729891, 12206.694832629255, 497555.0, 503823.0, 517612.0, 521120.0, 521975.0, 61447.38552614506, 75563.62218188983, 81885.0, 90333.54833225792, 94047.52976235075, 1073.2862646340252, 1117.3686866920164, 1131.0386171753944, 1136.3749937534324, 1144.2905398652888, 18604.44186674402, 18748.437546873614, 18808.341708040152, 18927.02964078107, 19100.14049367754, 41316.1736765269, 42315.788421057696, 43280.0, 43514.12971740611, 44443.77778111089, 61532.0, 63064.36935630769, 63726.0, 64229.0, 65361.67319163374, 9439.339246252972, 9460.858087128836, 9531.142197202285, 9646.372985755903, 9703.757406064904, 12999.545015924397, 13563.86436135626, 13759.312034398039, 14304.785428218376, 14599.0, 12042.0, 12165.574204902787, 12261.509539618315, 12276.079294052803, 12613.684657883627, 6499.057636642755, 10968.0, 12268.44791984345, 12396.566120185751, 12509.687257818627, 12077.637670869888, 12752.298623575793, 12829.743405131658, 12925.870741292843, 12994.220346779224, 9385.108414700593, 9481.76295592588, 9494.810103797747, 9519.571619277287, 9665.157831451172, 9585.376950498196, 12354.44405001795, 12601.495940162291, 12604.180728252633, 12818.102732808615], "value": 9868.874600128156, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.9483768068105, 1550.7596322570005, 1592.0209071421075, 1773.255232802223, 1901.3250296144868, 9397.953010234956, 9895.307328487008, 9901.108900198971, 10198.388096714203, 10217.489125543712, 9490.288228382882, 9749.512524373771, 9767.072128147815, 9769.023097690228, 9769.511524423786, 2185.278857976864, 2361.055577768889, 2433.4889673168655, 2499.7750202481775, 2532.645429639852, 2546.2743118211315, 2569.203546900467, 2572.3954870605394, 2576.755208255213, 2578.4198555324997, 2511.8744062796877, 2536.226450932465, 2540.212534114421, 2540.27602133392, 2543.1480454047914, 2140.5397839464513, 2463.864487453189, 2553.885075171622, 2554.5912653975274, 2569.049451702882, 2452.8773561321864, 2464.8521088734556, 2476.2571228631336, 2497.8501289922665, 2498.487809998946, 9985.550650220757, 9986.300958932878, 10041.24690648197, 10043.69868903934, 10065.295429319951, 5749.0, 5896.174535565025, 5905.43898329658, 5906.822795316148, 5917.14201440793, 3606.4049431843714, 3712.535933008374, 3741.5510138783247, 3749.718771092179, 3826.081740382288, 1166.0962753865738, 1175.6355529785708, 1181.1731787748524, 1198.6284251881943, 1233.272369302116, 14235.644108897257, 14263.500777472842, 14282.285885705673, 14286.357113930002, 14298.356573954197, 5077.111505486525, 5112.4120726116225, 5221.477852214824, 5268.815591454358, 5272.393674727378, 11520.366379849022, 11728.354940478184, 11766.176367654181, 11771.823422648667, 11779.941100294614, 5194.558462530727, 5967.31375891778, 5981.641101533922, 5985.850353741104, 5993.280806303272, 5256.632035757537, 5352.839414817562, 5396.406395296514, 5450.890982180414, 5454.400015998237, 4385.3641222022625, 4425.800838962255, 4463.62059224963, 4501.932471012938, 4511.977440112779, 1341.1885809085559, 1345.327336331834, 1379.8620137986322, 1384.439302082658, 1397.3083323754604, 926.4904302633524, 1082.410086502858, 1089.54239219528, 1093.1309608860975, 1120.6581992492368, 6444.13004244421, 6500.837479063061, 6509.479241660656, 6607.306232845578, 6665.100211471487, 477.97132172069786, 493.6371766751435, 494.16979474483213, 495.5391485918131, 502.29427654145985, 4690.752786944179, 4706.294055891575, 4755.904881902343, 4764.6664733469015, 4849.830255941025, 12100.152989290842, 12626.747465050648, 12629.115961882764, 12667.809982850265, 12679.873201267868, 11892.167548271538, 12317.630471085882, 12413.255204687566, 12838.229706217657, 12895.355232238351, 8537.573121344032, 8883.911160888438, 8887.644494220289, 8910.019897811235, 8925.687600933936, 6640.236372817188, 6856.348646878539, 6911.3779759819845, 7010.894836577353, 7013.544119632207, 11509.36698481558, 11600.7819178985, 11632.941835291107, 11762.0, 11851.0, 11535.942320288681, 11679.124065694938, 11965.521379145084, 11968.922796947969, 11979.101567382308, 8037.397195210499, 8290.626921788655, 8293.502389856629, 8812.51531165792, 9211.170994610517, 9132.954335228282, 9293.210077143518, 9640.0, 9690.030996900254, 9941.105300522699, 8707.869381959403, 8857.867131993155, 8884.955575222082, 8976.551172441483, 9039.0, 8603.225709685908, 8650.221480066824, 8717.798507648104, 8776.736697899074, 8873.73378798637, 8026.59867006659, 8028.1570435105805, 8110.79723006906, 8115.066767321833, 8119.5534245617055, 5489.286392768914, 5664.207011018543, 5703.287089113861, 5802.970985145047, 5840.532757379485, 5577.0, 5808.5643576732755, 5868.4424979626865, 5972.820815375546, 6065.54508411862], "value": 5070.752095521407, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.9063352894718, 353.8814497143456, 355.9697425718814, 360.1698085911975, 360.9584897736761, 5632.7183640818, 5659.971700141494, 5687.146927960803, 5718.59969802114, 5730.369659337474, 5276.92084618731, 5334.572769389928, 5517.641353312042, 5559.2488366164625, 5595.636283641571, 4324.6972711910175, 4353.151135528573, 4377.781110944456, 4379.189849877767, 4390.560943905608, 4365.257906155945, 4391.704447188071, 4401.141777353418, 4403.031333106712, 4462.152191083684, 4182.22628813669, 4192.287311157111, 4353.129374125172, 4364.2144414005625, 4376.321670141133, 4296.226679197744, 4303.6103174761565, 4318.093200427914, 4323.502797178333, 4368.453943257093, 3843.250566139604, 3858.9807050964564, 3862.9806850965847, 3885.708571857094, 3898.3372826619543, 5175.378954525481, 5291.1798671206025, 5293.86765330866, 5342.519173274424, 5383.946160538423, 4939.777710003059, 4965.131102057126, 4967.931894642633, 5013.498650134994, 5037.370328708911, 5716.71416429181, 5860.443257890536, 5870.182312489986, 5887.705614719247, 5889.499392551638, 3991.640752332304, 4227.450431443924, 4250.893727656802, 4252.723572967778, 4259.957400425987, 6042.667653279068, 6043.244594425697, 6102.115193296947, 6126.172966649535, 6139.171211886427, 4333.783310834446, 4336.609705126491, 4337.6963612546815, 4341.175176716414, 4343.500497442835, 4152.0485371875175, 4346.478422589246, 4358.956410435919, 4374.234508960919, 4390.780460976938, 4917.237828136573, 4947.579455746302, 4966.602671786249, 5047.848564543069, 5071.290019397287, 5782.421757824184, 5988.161657367972, 6007.429294217042, 6013.879722405527, 6033.849153771191, 1439.9712005759827, 1511.8866085043446, 1517.924103794806, 1518.7342215112312, 1519.6475137127743, 990.6037584965927, 997.4813097189566, 997.9051990060933, 1008.2790804574618, 1008.3042700536606, 4961.5286547777905, 4982.800687972513, 4992.951480189128, 5034.572061374751, 5067.961067981055, 5274.551663108602, 5298.523132918056, 5340.572754179656, 5353.65396627108, 5368.275282836845, 4853.587445067138, 5018.84943451697, 5048.217526283432, 5052.34319538465, 5053.67151135175, 2804.8445498813835, 2813.71862813717, 2835.5822088955524, 2847.501687204772, 2867.0, 5329.934013197376, 5362.544183744348, 5450.182472629137, 5457.253678822818, 5482.629342664334, 5345.54562862161, 5371.409144994048, 5766.279215098113, 6195.752169913243, 6216.222972128484, 6219.626822390581, 6269.655168965751, 6300.810975670737, 6314.526410519229, 6315.652639104802, 5858.297004359504, 6080.300765412034, 6132.846678833065, 6134.447899689049, 6140.478059364826, 5999.700014999145, 6015.135308054517, 6020.939790602038, 6023.909641355471, 6026.668533230714, 6256.280527739368, 6260.530460215411, 6285.968570157303, 6301.401366870324, 6309.432151106423, 4192.098698779777, 4305.82776688929, 4307.590778876001, 4338.349247612883, 4343.326784348431, 5825.854353641193, 6049.78825741097, 6135.631862088289, 6136.8772624548155, 6145.477634401126, 5856.3558008618165, 5888.4700376966275, 5932.911006334822, 5939.90111829313, 5972.163897054329, 5904.852378690567, 5919.437653422918, 5923.7926672566255, 5955.166276721176, 5959.523238141025, 5591.916121258103, 5698.287714035746, 5754.741036653444, 5758.510526605118, 5826.999095465923, 5875.794347197828, 5881.0, 5890.293164820249, 5897.882042359214, 5926.547995741071, 5668.829935101953, 5778.359633430911, 5793.507551858139, 5860.531157507475, 5886.3223981165165], "value": 4406.905804133865, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.1167436633787, 809.1099790230745, 819.6844214977232, 841.9747407577775, 853.3727710133052, 16399.836001639997, 16543.66912661749, 16740.83259167408, 17507.0, 17683.0, 15617.21913904306, 15766.684666306666, 15781.763273550878, 15902.204889755494, 15930.442434514795, 744.7244519527783, 863.4085651328828, 878.6046279174382, 887.6227603268617, 888.6045709659204, 875.1117615620151, 876.5354362188051, 877.1272583779148, 878.9560521973908, 879.3448880583974, 931.9440833549975, 934.6027938126269, 935.0415823780611, 935.6771913689801, 936.5176933879058, 779.5011192836561, 800.9759707208764, 913.4610579757974, 929.8930622978343, 938.0197693410403, 692.2454524568194, 692.7125243024138, 693.3378623414627, 693.5387967001938, 693.743314973458, 16258.105804180765, 16273.267702953395, 16325.59186020359, 16345.673086538322, 16383.0, 14881.10713357202, 14955.177465239407, 15100.924495377563, 15137.394504219817, 15612.141332226724, 13258.46966121354, 13387.263700496469, 13393.799093013613, 13472.730545389037, 13821.930890345584, 4696.7651617419, 4722.480527142012, 4738.2892566115015, 4761.880952976168, 4852.344933434012, 67769.66115169393, 75836.70906889485, 80230.59884700539, 80322.59838700827, 80650.0, 11689.532418703327, 11888.0, 11989.940050299867, 12031.217970831867, 12065.396730163633, 21126.78873211279, 21269.46826329354, 21387.46531336729, 21407.892960535097, 21534.56930861374, 8932.017478077407, 9090.36367454285, 9320.580573874191, 9460.905390946002, 9788.853167202498, 5115.667481613683, 5136.948630513647, 5181.4041385240425, 5246.396664383568, 5327.3074500314715, 3676.8161591920298, 3765.755225910307, 3767.3030489359558, 3768.2086761780333, 3791.829367678461, 561.4890449690762, 698.2354322017383, 705.7000774670745, 707.0172460280214, 707.7204504220804, 662.4667142949875, 712.5261700968798, 726.785598248518, 810.6433169405443, 833.2251006563954, 10400.635977740743, 10441.321314114559, 11150.442477876073, 11211.103111751203, 11281.71795705114, 619.3775255867914, 654.6693919570587, 702.3327838553395, 766.9693212271558, 799.2447137455049, 4963.875903102379, 5088.160453525201, 5286.7356632169, 5311.3095297611835, 5386.326709161355, 74793.0, 76143.61928190435, 95623.56564651536, 98305.0, 99630.5018474903, 93462.53268733613, 95975.52012239894, 96376.0, 96702.51648741712, 98692.50653746829, 87140.5642971781, 88085.0, 88099.8845035644, 88376.558117209, 89914.55042724876, 10179.733113001574, 10290.279680422298, 10608.0, 10718.35689858611, 10820.729481763019, 52177.7391113042, 52328.47671523236, 52736.20895686491, 52846.735766320926, 52934.47065529449, 67494.32505675076, 68215.65892170508, 75745.24254757381, 77780.0, 78470.0, 13646.317684115953, 13845.861541384456, 13927.23400212998, 13960.0, 14771.556853294418, 15570.455034073753, 15642.843571564139, 16086.758698619764, 16524.0, 16774.748378774573, 14225.644358890697, 14730.705385892437, 14950.551483455514, 15229.314680839167, 15770.448034318744, 14662.413503459742, 15010.849891500946, 15458.149801761012, 15674.76487852706, 15985.44050958211, 14642.633934151732, 14784.556463306119, 14847.0, 14987.700245995236, 15048.322825473098, 11542.192046556997, 11542.769144616892, 11851.940740296242, 11967.10246731516, 12070.879291206975, 11493.482793274155, 11563.88436115628, 11859.466324015268, 11988.220765650205, 12136.757264854477], "value": 8021.819569658916, "unit": "score"}], "name": "dromaeo_css", "value": 7709.849483357728, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 111.0, 111.0, 109.0, 112.0, 110.0, 110.0, 109.0, 108.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 130.0, 175.0, 125.0, 124.0, 151.0, 123.0, 158.0, 137.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 161.0, 161.0, 160.0, 164.0, 160.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 122.0, 75.0, 75.0, 74.0, 75.0, 97.0, 75.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 112.0, 84.0, 84.0, 85.0, 113.0, 86.0, 85.0, 121.0], "value": 94.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 53.0, 52.0, 53.0, 51.0, 52.0, 52.0, 52.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 76.0, 77.0, 78.0, 82.0, 79.0, 79.0, 134.0, 82.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 115.0, 122.0, 116.0, 120.0, 127.0, 113.0, 116.0, 139.0, 136.0], "value": 122.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 156.0, 156.0, 154.0, 150.0, 153.0, 162.0, 155.0, 151.0, 153.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 62.0, 61.0, 61.0, 63.0, 60.0, 61.0, 64.0, 60.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1477.1000000000001, "alertThreshold": 2.0}]} 23:11:08 INFO - Return code: 0 23:11:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:11:08 ERROR - # TBPL SUCCESS # 23:11:08 INFO - Running post-action listener: _resource_record_post_action 23:11:08 INFO - ##### 23:11:08 INFO - ##### Finished run-tests step (success) 23:11:08 INFO - ##### 23:11:08 INFO - Running post-run listener: _resource_record_post_run 23:11:08 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 512774144; Read time: 8; Write time: 822772 23:11:08 INFO - TinderboxPrint: CPU usage
13.1% 23:11:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:11:08 INFO - TinderboxPrint: I/O write bytes / time
512,774,144 / 822,772 23:11:08 INFO - TinderboxPrint: CPU idle
8,255.6 (86.8%) 23:11:08 INFO - TinderboxPrint: CPU user
1,199.6 (12.6%) 23:11:08 INFO - TinderboxPrint: Swap in / out
0 / 0 23:11:08 INFO - install - Wall time: 16s; CPU: 16.0%; Read bytes: 0; Write bytes: 262602752; Read time: 0; Write time: 543676 23:11:08 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 249585664; Read time: 8; Write time: 279056 23:11:09 INFO - Running post-run listener: _upload_blobber_files 23:11:09 INFO - Blob upload gear active. 23:11:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:11:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:11:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:11:09 INFO - (blobuploader) - INFO - Open directory for files ... 23:11:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:11:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:11:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:11:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:11:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:11:13 INFO - (blobuploader) - INFO - Done attempting. 23:11:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:11:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:11:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:11:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:11:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:11:14 INFO - (blobuploader) - INFO - Done attempting. 23:11:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:11:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:11:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:11:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:11:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:11:15 INFO - (blobuploader) - INFO - Done attempting. 23:11:15 INFO - (blobuploader) - INFO - Iteration through files over. 23:11:15 INFO - Return code: 0 23:11:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:11:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:11:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2dd9e561733d99e4891d5eafac4f4309e4a4a42db16da025a08386f47d9621319ba516de2750013157f5529a85b9b11e784cfa52d3e2d9976be6f6d59ae96938", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0d8f798e8a6c227a5056f0f315504bc831b5edcbfdc6517f241836a0b9aae7d395d275b492d89290ecc7c9dba63be21c2e2af655713daa3a4e61c8add080b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f1f80f6224c3696ca3b3abf7da8c9b94d9bed365f433037b8d8c857243f4f59bb7c6c021bb185e21ed4739032be41d861b840d4ac801c02832b6acd79ca922a"} 23:11:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:11:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:11:15 INFO - Contents: 23:11:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2dd9e561733d99e4891d5eafac4f4309e4a4a42db16da025a08386f47d9621319ba516de2750013157f5529a85b9b11e784cfa52d3e2d9976be6f6d59ae96938", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0d8f798e8a6c227a5056f0f315504bc831b5edcbfdc6517f241836a0b9aae7d395d275b492d89290ecc7c9dba63be21c2e2af655713daa3a4e61c8add080b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f1f80f6224c3696ca3b3abf7da8c9b94d9bed365f433037b8d8c857243f4f59bb7c6c021bb185e21ed4739032be41d861b840d4ac801c02832b6acd79ca922a"} 23:11:15 INFO - Running post-run listener: copy_logs_to_upload_dir 23:11:15 INFO - Copying logs to upload dir... 23:11:15 INFO - mkdir: /builds/slave/test/build/upload/logs 23:11:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.945386 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-06-06 23:11:15.224358) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 23:11:15.229157) ========= 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=af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2dd9e561733d99e4891d5eafac4f4309e4a4a42db16da025a08386f47d9621319ba516de2750013157f5529a85b9b11e784cfa52d3e2d9976be6f6d59ae96938", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0d8f798e8a6c227a5056f0f315504bc831b5edcbfdc6517f241836a0b9aae7d395d275b492d89290ecc7c9dba63be21c2e2af655713daa3a4e61c8add080b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f1f80f6224c3696ca3b3abf7da8c9b94d9bed365f433037b8d8c857243f4f59bb7c6c021bb185e21ed4739032be41d861b840d4ac801c02832b6acd79ca922a"} build_url:https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011294 build_url: 'https://queue.taskcluster.net/v1/task/Q4TsFUxRQcOCi0AHQQ1VQg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2dd9e561733d99e4891d5eafac4f4309e4a4a42db16da025a08386f47d9621319ba516de2750013157f5529a85b9b11e784cfa52d3e2d9976be6f6d59ae96938", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0d8f798e8a6c227a5056f0f315504bc831b5edcbfdc6517f241836a0b9aae7d395d275b492d89290ecc7c9dba63be21c2e2af655713daa3a4e61c8add080b72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f1f80f6224c3696ca3b3abf7da8c9b94d9bed365f433037b8d8c857243f4f59bb7c6c021bb185e21ed4739032be41d861b840d4ac801c02832b6acd79ca922a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 23:11:15.255061) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:11:15.255395) ========= 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=af724c981e2a0f424dbabf99000001e7-1465275848.100188-625980066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 23:11:15.339743) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 23:11:15.340143) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 23:11:15.340462) ========= ========= Total master_lag: 0.18 =========