builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-042 starttime: 1461587015.76 results: success (0) buildid: 20160422035423 builduid: e1d26a5cd477466bafcab7d720b7b498 revision: 0361b7030d363459416d46e651a8c50c9536788f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:35.762885) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:35.763365) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:35.763660) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:35.779894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:35.780176) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 05:23:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 151M=0s 2016-04-25 05:23:36 (151 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.147895 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:35.941404) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:35.941725) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019900 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:35.991147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 05:23:35.991463) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0361b7030d363459416d46e651a8c50c9536788f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0361b7030d363459416d46e651a8c50c9536788f --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=74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 05:23:36,556 truncating revision to first 12 chars 2016-04-25 05:23:36,556 Setting DEBUG logging. 2016-04-25 05:23:36,556 attempt 1/10 2016-04-25 05:23:36,556 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0361b7030d36?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 05:23:37,271 unpacking tar archive at: fx-team-0361b7030d36/testing/mozharness/ program finished with exit code 0 elapsedTime=1.015497 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 05:23:37.019941) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:37.020256) ========= script_repo_revision: 0361b7030d363459416d46e651a8c50c9536788f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:37.020624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:37.020901) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 05:23:37.283026) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-04-25 05:23:37.283406) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:23:37 INFO - MultiFileLogger online at 20160425 05:23:37 in /builds/slave/test 05:23:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 05:23:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:23:37 INFO - {'append_to_log': False, 05:23:37 INFO - 'base_work_dir': '/builds/slave/test', 05:23:37 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:23:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:23:37 INFO - 'branch': 'Fx-Team-Non-PGO', 05:23:37 INFO - 'buildbot_json_path': 'buildprops.json', 05:23:37 INFO - 'config_files': ('talos/linux_config.py',), 05:23:37 INFO - 'default_actions': ('clobber', 05:23:37 INFO - 'read-buildbot-config', 05:23:37 INFO - 'download-and-extract', 05:23:37 INFO - 'populate-webroot', 05:23:37 INFO - 'create-virtualenv', 05:23:37 INFO - 'install', 05:23:37 INFO - 'run-tests'), 05:23:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:23:37 INFO - 'download_minidump_stackwalk': True, 05:23:37 INFO - 'download_symbols': 'ondemand', 05:23:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:23:37 INFO - 'tooltool.py': '/tools/tooltool.py', 05:23:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:23:37 INFO - '/tools/misc-python/virtualenv.py')}, 05:23:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:23:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:23:37 INFO - 'installer_path': 'installer.exe', 05:23:37 INFO - 'log_level': 'info', 05:23:37 INFO - 'log_name': 'talos', 05:23:37 INFO - 'log_to_console': True, 05:23:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:23:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:23:37 INFO - 'opt_config_files': (), 05:23:37 INFO - 'pip_index': False, 05:23:37 INFO - 'sps_profile': False, 05:23:37 INFO - 'sps_profile_interval': 0, 05:23:37 INFO - 'suite': 'dromaeojs-e10s', 05:23:37 INFO - 'system_bits': '32', 05:23:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:37 INFO - 'title': 'talos-linux64-ix-042', 05:23:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:23:37 INFO - 'use_talos_json': True, 05:23:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:23:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:23:37 INFO - 'work_dir': 'build'} 05:23:37 INFO - ##### 05:23:37 INFO - ##### Running clobber step. 05:23:37 INFO - ##### 05:23:37 INFO - Running pre-action listener: _resource_record_pre_action 05:23:37 INFO - Running main action method: clobber 05:23:37 INFO - rmtree: /builds/slave/test/build 05:23:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:38 INFO - Running post-action listener: _resource_record_post_action 05:23:38 INFO - ##### 05:23:38 INFO - ##### Running read-buildbot-config step. 05:23:38 INFO - ##### 05:23:38 INFO - Running pre-action listener: _resource_record_pre_action 05:23:38 INFO - Running main action method: read_buildbot_config 05:23:38 INFO - Using buildbot properties: 05:23:38 INFO - { 05:23:38 INFO - "project": "", 05:23:38 INFO - "product": "firefox", 05:23:38 INFO - "script_repo_revision": "production", 05:23:38 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:23:38 INFO - "repository": "", 05:23:38 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 05:23:38 INFO - "buildid": "20160422035423", 05:23:38 INFO - "pgo_build": "False", 05:23:38 INFO - "basedir": "/builds/slave/test", 05:23:38 INFO - "buildnumber": 1443, 05:23:38 INFO - "slavename": "talos-linux64-ix-042", 05:23:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:23:38 INFO - "platform": "ubuntu64_hw", 05:23:38 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:23:38 INFO - "branch": "fx-team", 05:23:38 INFO - "repo_path": "integration/fx-team", 05:23:38 INFO - "revision": "0361b7030d363459416d46e651a8c50c9536788f", 05:23:38 INFO - "stage_platform": "linux64", 05:23:38 INFO - "builduid": "e1d26a5cd477466bafcab7d720b7b498", 05:23:38 INFO - "slavebuilddir": "test" 05:23:38 INFO - } 05:23:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 05:23:38 INFO - Running post-action listener: _resource_record_post_action 05:23:38 INFO - ##### 05:23:38 INFO - ##### Running download-and-extract step. 05:23:38 INFO - ##### 05:23:38 INFO - Running pre-action listener: _resource_record_pre_action 05:23:38 INFO - Running main action method: download_and_extract 05:23:38 INFO - mkdir: /builds/slave/test/build/tests 05:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:38 INFO - https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:23:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:23:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:23:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:23:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:23:39 INFO - Downloaded 1361 bytes. 05:23:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:23:39 INFO - Using the following test package requirements: 05:23:39 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:39 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:39 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:23:39 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:39 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:23:39 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:39 INFO - u'jsshell-linux-x86_64.zip'], 05:23:39 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:39 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:23:39 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:39 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:39 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:23:39 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:39 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:23:39 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:39 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:23:39 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:39 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:23:39 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:23:39 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')]} 05:23:39 INFO - https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:23:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:23:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:23:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:23:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:23:40 INFO - Downloaded 22281373 bytes. 05:23:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:23:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:23:41 INFO - Return code: 0 05:23:41 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:23: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')]} 05:23:41 INFO - https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:23:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:23:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:23:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:23:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:23:42 INFO - Downloaded 22281373 bytes. 05:23:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:23:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:23:44 INFO - Return code: 0 05:23: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')]} 05:23:44 INFO - https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:23:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:23:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:23:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:23:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:23:45 INFO - Downloaded 10845952 bytes. 05:23:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:23:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:23:45 INFO - Return code: 0 05:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:45 INFO - https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:23:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:23:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:23:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:23:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:23:46 INFO - Downloaded 54649298 bytes. 05:23:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:23:46 INFO - mkdir: /builds/slave/test/properties 05:23:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:46 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:46 INFO - Contents: 05:23:46 INFO - build_url:https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:23:47 INFO - Running post-action listener: _resource_record_post_action 05:23:47 INFO - Running post-action listener: set_extra_try_arguments 05:23:47 INFO - ##### 05:23:47 INFO - ##### Running populate-webroot step. 05:23:47 INFO - ##### 05:23:47 INFO - Running pre-action listener: _resource_record_pre_action 05:23:47 INFO - Running main action method: populate_webroot 05:23:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:47 INFO - '--tppagecycles', 05:23:47 INFO - '7'], 05:23:47 INFO - 'tests': ['tsvgm']}}, 05:23:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:47 INFO - 'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:47 INFO - 'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['damp', 'tps']}, 05:23:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:47 INFO - 'tests': ['damp', 'tps']}, 05:23:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['dromaeo_dom']}, 05:23:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['a11yr', 05:23:47 INFO - 'ts_paint', 05:23:47 INFO - 'tpaint', 05:23:47 INFO - 'sessionrestore', 05:23:47 INFO - 'sessionrestore_no_auto_restore', 05:23:47 INFO - 'tabpaint']}, 05:23:47 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:47 INFO - 'ts_paint', 05:23:47 INFO - 'tpaint', 05:23:47 INFO - 'sessionrestore', 05:23:47 INFO - 'sessionrestore_no_auto_restore', 05:23:47 INFO - 'tabpaint']}, 05:23:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:47 INFO - 'ts_paint', 05:23:47 INFO - 'tpaint', 05:23:47 INFO - 'sessionrestore', 05:23:47 INFO - 'sessionrestore_no_auto_restore', 05:23:47 INFO - 'tabpaint']}, 05:23:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:47 INFO - 'ts_paint', 05:23:47 INFO - 'tpaint', 05:23:47 INFO - 'sessionrestore', 05:23:47 INFO - 'sessionrestore_no_auto_restore', 05:23:47 INFO - 'tabpaint']}, 05:23:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['a11yr', 05:23:47 INFO - 'ts_paint', 05:23:47 INFO - 'tpaint', 05:23:47 INFO - 'sessionrestore', 05:23:47 INFO - 'sessionrestore_no_auto_restore', 05:23:47 INFO - 'tabpaint']}, 05:23:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['a11yr', 05:23:47 INFO - 'ts_paint', 05:23:47 INFO - 'tpaint', 05:23:47 INFO - 'sessionrestore', 05:23:47 INFO - 'sessionrestore_no_auto_restore', 05:23:47 INFO - 'tabpaint']}, 05:23:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['tsvgx', 05:23:47 INFO - 'tsvgr_opacity', 05:23:47 INFO - 'tart', 05:23:47 INFO - 'tscrollx', 05:23:47 INFO - 'cart']}, 05:23:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:47 INFO - 'tsvgr_opacity', 05:23:47 INFO - 'tart', 05:23:47 INFO - 'tscrollx', 05:23:47 INFO - 'cart']}, 05:23:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:47 INFO - 'talos_options': ['--disable-e10s'], 05:23:47 INFO - 'tests': ['tp5o']}, 05:23:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:47 INFO - 'tests': ['tp5o']}, 05:23:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:47 INFO - 'talos_options': ['--disable-e10s', 05:23:47 INFO - '--xperf_path', 05:23:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:47 INFO - 'tests': ['tp5n']}, 05:23:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:47 INFO - 'talos_options': ['--xperf_path', 05:23:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:47 INFO - 'tests': ['tp5n']}}, 05:23:47 INFO - 'talos.zip': {'path': '', 05:23:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:47 INFO - Running post-action listener: _resource_record_post_action 05:23:47 INFO - ##### 05:23:47 INFO - ##### Running create-virtualenv step. 05:23:47 INFO - ##### 05:23:47 INFO - Running pre-action listener: _resource_record_pre_action 05:23:47 INFO - Running main action method: create_virtualenv 05:23:47 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23:47 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 05:23:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:47 INFO - Using real prefix '/usr' 05:23:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:23:48 INFO - Installing distribute.............................................................................................................................................................................................done. 05:23:50 INFO - Installing pip.................done. 05:23:50 INFO - Return code: 0 05:23:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:23:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:23:50 INFO - Using env: {'DISPLAY': ':0', 05:23:50 INFO - 'HOME': '/home/cltbld', 05:23:50 INFO - 'LANG': 'en_US.UTF-8', 05:23:50 INFO - 'LANGUAGE': 'en_US:en', 05:23:50 INFO - 'LOGNAME': 'cltbld', 05:23:50 INFO - 'MAIL': '/var/mail/cltbld', 05:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:50 INFO - 'MOZ_NO_REMOTE': '1', 05:23:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:50 INFO - 'NO_EM_RESTART': '1', 05:23:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:50 INFO - 'PWD': '/builds/slave/test', 05:23:50 INFO - 'SHELL': '/bin/bash', 05:23:50 INFO - 'SHLVL': '1', 05:23:50 INFO - 'TERM': 'linux', 05:23:50 INFO - 'TMOUT': '86400', 05:23:50 INFO - 'USER': 'cltbld', 05:23:50 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:50 INFO - '_': '/tools/buildbot/bin/python'} 05:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:50 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 05:23:50 INFO - Cleaning up... 05:23:50 INFO - Return code: 0 05:23:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:23:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:23:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:23:50 INFO - Using env: {'DISPLAY': ':0', 05:23:50 INFO - 'HOME': '/home/cltbld', 05:23:50 INFO - 'LANG': 'en_US.UTF-8', 05:23:50 INFO - 'LANGUAGE': 'en_US:en', 05:23:50 INFO - 'LOGNAME': 'cltbld', 05:23:50 INFO - 'MAIL': '/var/mail/cltbld', 05:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:50 INFO - 'MOZ_NO_REMOTE': '1', 05:23:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:50 INFO - 'NO_EM_RESTART': '1', 05:23:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:50 INFO - 'PWD': '/builds/slave/test', 05:23:50 INFO - 'SHELL': '/bin/bash', 05:23:50 INFO - 'SHLVL': '1', 05:23:50 INFO - 'TERM': 'linux', 05:23:50 INFO - 'TMOUT': '86400', 05:23:50 INFO - 'USER': 'cltbld', 05:23:50 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:50 INFO - '_': '/tools/buildbot/bin/python'} 05:23:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:50 INFO - Downloading/unpacking psutil>=0.7.1 05:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:23:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:52 INFO - Installing collected packages: psutil 05:23:52 INFO - Running setup.py install for psutil 05:23:52 INFO - building 'psutil._psutil_linux' extension 05:23:52 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 05:23:53 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 05:23:53 INFO - building 'psutil._psutil_posix' extension 05:23:53 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 05:23:53 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 05:23:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:53 INFO - Successfully installed psutil 05:23:53 INFO - Cleaning up... 05:23:53 INFO - Return code: 0 05:23:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:23: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')]} 05:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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')]} 05:23:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:23: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:23: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 mozsystemmonitor==0.0.0 05:23:53 INFO - Using env: {'DISPLAY': ':0', 05:23:53 INFO - 'HOME': '/home/cltbld', 05:23:53 INFO - 'LANG': 'en_US.UTF-8', 05:23:53 INFO - 'LANGUAGE': 'en_US:en', 05:23:53 INFO - 'LOGNAME': 'cltbld', 05:23:53 INFO - 'MAIL': '/var/mail/cltbld', 05:23:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:53 INFO - 'MOZ_NO_REMOTE': '1', 05:23:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:53 INFO - 'NO_EM_RESTART': '1', 05:23:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:53 INFO - 'PWD': '/builds/slave/test', 05:23:53 INFO - 'SHELL': '/bin/bash', 05:23:53 INFO - 'SHLVL': '1', 05:23:53 INFO - 'TERM': 'linux', 05:23:53 INFO - 'TMOUT': '86400', 05:23:53 INFO - 'USER': 'cltbld', 05:23:53 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:23:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:53 INFO - '_': '/tools/buildbot/bin/python'} 05:23:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:23:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:23:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:23:55 INFO - Installing collected packages: mozsystemmonitor 05:23:55 INFO - Running setup.py install for mozsystemmonitor 05:23:55 INFO - Successfully installed mozsystemmonitor 05:23:55 INFO - Cleaning up... 05:23:55 INFO - Return code: 0 05:23:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:23:55 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')]} 05:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:55 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')]} 05:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:55 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 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:23:55 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 05:23:55 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 05:23:55 INFO - Using env: {'DISPLAY': ':0', 05:23:55 INFO - 'HOME': '/home/cltbld', 05:23:55 INFO - 'LANG': 'en_US.UTF-8', 05:23:55 INFO - 'LANGUAGE': 'en_US:en', 05:23:55 INFO - 'LOGNAME': 'cltbld', 05:23:55 INFO - 'MAIL': '/var/mail/cltbld', 05:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:55 INFO - 'MOZ_NO_REMOTE': '1', 05:23:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:55 INFO - 'NO_EM_RESTART': '1', 05:23:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:55 INFO - 'PWD': '/builds/slave/test', 05:23:55 INFO - 'SHELL': '/bin/bash', 05:23:55 INFO - 'SHLVL': '1', 05:23:55 INFO - 'TERM': 'linux', 05:23:55 INFO - 'TMOUT': '86400', 05:23:55 INFO - 'USER': 'cltbld', 05:23:55 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:55 INFO - '_': '/tools/buildbot/bin/python'} 05:23:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:55 INFO - Downloading/unpacking blobuploader==1.2.4 05:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:57 INFO - Downloading blobuploader-1.2.4.tar.gz 05:23:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:23:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23: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 05:23:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:23:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:58 INFO - Downloading docopt-0.6.1.tar.gz 05:23:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:23:58 INFO - Installing collected packages: blobuploader, requests, docopt 05:23:58 INFO - Running setup.py install for blobuploader 05:23:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:23:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:23:58 INFO - Running setup.py install for requests 05:23:58 INFO - Running setup.py install for docopt 05:23:59 INFO - Successfully installed blobuploader requests docopt 05:23:59 INFO - Cleaning up... 05:23:59 INFO - Return code: 0 05:23:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:59 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 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:23:59 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 05:23:59 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 05:23:59 INFO - Using env: {'DISPLAY': ':0', 05:23:59 INFO - 'HOME': '/home/cltbld', 05:23:59 INFO - 'LANG': 'en_US.UTF-8', 05:23:59 INFO - 'LANGUAGE': 'en_US:en', 05:23:59 INFO - 'LOGNAME': 'cltbld', 05:23:59 INFO - 'MAIL': '/var/mail/cltbld', 05:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:59 INFO - 'MOZ_NO_REMOTE': '1', 05:23:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:59 INFO - 'NO_EM_RESTART': '1', 05:23:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:59 INFO - 'PWD': '/builds/slave/test', 05:23:59 INFO - 'SHELL': '/bin/bash', 05:23:59 INFO - 'SHLVL': '1', 05:23:59 INFO - 'TERM': 'linux', 05:23:59 INFO - 'TMOUT': '86400', 05:23:59 INFO - 'USER': 'cltbld', 05:23:59 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:59 INFO - '_': '/tools/buildbot/bin/python'} 05:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:59 INFO - Running setup.py (path:/tmp/pip-O8sKtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:59 INFO - Running setup.py (path:/tmp/pip-PHCgX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:59 INFO - Running setup.py (path:/tmp/pip-t3SfsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:59 INFO - Running setup.py (path:/tmp/pip-0W4DRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:59 INFO - Running setup.py (path:/tmp/pip-NAXZbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:59 INFO - Running setup.py (path:/tmp/pip-_Q0FMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:59 INFO - Running setup.py (path:/tmp/pip-JssdTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:59 INFO - Running setup.py (path:/tmp/pip-yMZtDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:59 INFO - Running setup.py (path:/tmp/pip-ydwXQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:59 INFO - Running setup.py (path:/tmp/pip-Q1ay8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:00 INFO - Running setup.py (path:/tmp/pip-cY2cnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:00 INFO - Running setup.py (path:/tmp/pip-yiZZ87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:00 INFO - Running setup.py (path:/tmp/pip-INkRUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:00 INFO - Running setup.py (path:/tmp/pip-PYrwTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:00 INFO - Running setup.py (path:/tmp/pip-BSNLYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:00 INFO - Running setup.py (path:/tmp/pip-cQtnRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:00 INFO - Running setup.py (path:/tmp/pip-zFLFl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:24:00 INFO - Running setup.py install for manifestparser 05:24:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:24:00 INFO - Running setup.py install for mozcrash 05:24:00 INFO - Running setup.py install for mozdebug 05:24:00 INFO - Running setup.py install for mozdevice 05:24:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:24:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:24:00 INFO - Running setup.py install for mozfile 05:24:01 INFO - Running setup.py install for mozhttpd 05:24:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Running setup.py install for mozinfo 05:24:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Running setup.py install for mozInstall 05:24:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Running setup.py install for mozleak 05:24:01 INFO - Running setup.py install for mozlog 05:24:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Running setup.py install for moznetwork 05:24:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Running setup.py install for mozprocess 05:24:01 INFO - Running setup.py install for mozprofile 05:24:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:24:01 INFO - Running setup.py install for mozrunner 05:24:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:24:02 INFO - Running setup.py install for mozscreenshot 05:24:02 INFO - Running setup.py install for moztest 05:24:02 INFO - Running setup.py install for mozversion 05:24:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:24:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:24:02 INFO - Cleaning up... 05:24:02 INFO - Return code: 0 05:24:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:02 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 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:24:02 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 05:24:02 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 05:24:02 INFO - Using env: {'DISPLAY': ':0', 05:24:02 INFO - 'HOME': '/home/cltbld', 05:24:02 INFO - 'LANG': 'en_US.UTF-8', 05:24:02 INFO - 'LANGUAGE': 'en_US:en', 05:24:02 INFO - 'LOGNAME': 'cltbld', 05:24:02 INFO - 'MAIL': '/var/mail/cltbld', 05:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:02 INFO - 'MOZ_NO_REMOTE': '1', 05:24:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:02 INFO - 'NO_EM_RESTART': '1', 05:24:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:02 INFO - 'PWD': '/builds/slave/test', 05:24:02 INFO - 'SHELL': '/bin/bash', 05:24:02 INFO - 'SHLVL': '1', 05:24:02 INFO - 'TERM': 'linux', 05:24:02 INFO - 'TMOUT': '86400', 05:24:02 INFO - 'USER': 'cltbld', 05:24:02 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:02 INFO - '_': '/tools/buildbot/bin/python'} 05:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:02 INFO - Running setup.py (path:/tmp/pip-rIap15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:02 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)) 05:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:02 INFO - Running setup.py (path:/tmp/pip-o4nhxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:02 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)) 05:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:02 INFO - Running setup.py (path:/tmp/pip-aSou26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:02 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)) 05:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:02 INFO - Running setup.py (path:/tmp/pip-CteWb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:02 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)) 05:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:02 INFO - Running setup.py (path:/tmp/pip-ETPAJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:02 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)) 05:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:02 INFO - Running setup.py (path:/tmp/pip-hGu9oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:02 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)) 05:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:02 INFO - Running setup.py (path:/tmp/pip-62LZ3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:03 INFO - Running setup.py (path:/tmp/pip-gVfubt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:03 INFO - Running setup.py (path:/tmp/pip-IC2KC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:03 INFO - Running setup.py (path:/tmp/pip-uOO_S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:03 INFO - Running setup.py (path:/tmp/pip-4TVciW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:03 INFO - Running setup.py (path:/tmp/pip-yhNG8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:03 INFO - Running setup.py (path:/tmp/pip-EBOMZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:03 INFO - Running setup.py (path:/tmp/pip-B3ypbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:03 INFO - Running setup.py (path:/tmp/pip-4IeF3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:03 INFO - Running setup.py (path:/tmp/pip-pROC8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:03 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)) 05:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:03 INFO - Running setup.py (path:/tmp/pip-l6_cNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:03 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)) 05:24:03 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)) 05:24:03 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)) 05:24:03 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)) 05:24:03 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)) 05:24:03 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)) 05:24:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:05 INFO - Downloading blessings-1.6.tar.gz 05:24:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:24:05 INFO - Installing collected packages: blessings 05:24:05 INFO - Running setup.py install for blessings 05:24:05 INFO - Successfully installed blessings 05:24:05 INFO - Cleaning up... 05:24:05 INFO - Return code: 0 05:24:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:24:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:06 INFO - Reading from file tmpfile_stdout 05:24:06 INFO - Current package versions: 05:24:06 INFO - argparse == 1.2.1 05:24:06 INFO - blessings == 1.6 05:24:06 INFO - blobuploader == 1.2.4 05:24:06 INFO - docopt == 0.6.1 05:24:06 INFO - manifestparser == 1.1 05:24:06 INFO - mozInstall == 1.12 05:24:06 INFO - mozcrash == 0.17 05:24:06 INFO - mozdebug == 0.1 05:24:06 INFO - mozdevice == 0.48 05:24:06 INFO - mozfile == 1.2 05:24:06 INFO - mozhttpd == 0.7 05:24:06 INFO - mozinfo == 0.9 05:24:06 INFO - mozleak == 0.1 05:24:06 INFO - mozlog == 3.1 05:24:06 INFO - moznetwork == 0.27 05:24:06 INFO - mozprocess == 0.22 05:24:06 INFO - mozprofile == 0.28 05:24:06 INFO - mozrunner == 6.11 05:24:06 INFO - mozscreenshot == 0.1 05:24:06 INFO - mozsystemmonitor == 0.0 05:24:06 INFO - moztest == 0.7 05:24:06 INFO - mozversion == 1.4 05:24:06 INFO - psutil == 3.1.1 05:24:06 INFO - requests == 1.2.3 05:24:06 INFO - wsgiref == 0.1.2 05:24:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:06 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 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:24:06 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 05:24:06 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 05:24:06 INFO - Using env: {'DISPLAY': ':0', 05:24:06 INFO - 'HOME': '/home/cltbld', 05:24:06 INFO - 'LANG': 'en_US.UTF-8', 05:24:06 INFO - 'LANGUAGE': 'en_US:en', 05:24:06 INFO - 'LOGNAME': 'cltbld', 05:24:06 INFO - 'MAIL': '/var/mail/cltbld', 05:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:06 INFO - 'MOZ_NO_REMOTE': '1', 05:24:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:06 INFO - 'NO_EM_RESTART': '1', 05:24:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:06 INFO - 'PWD': '/builds/slave/test', 05:24:06 INFO - 'SHELL': '/bin/bash', 05:24:06 INFO - 'SHLVL': '1', 05:24:06 INFO - 'TERM': 'linux', 05:24:06 INFO - 'TMOUT': '86400', 05:24:06 INFO - 'USER': 'cltbld', 05:24:06 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:06 INFO - '_': '/tools/buildbot/bin/python'} 05:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 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)) 05:24:06 INFO - Cleaning up... 05:24:06 INFO - Return code: 0 05:24:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:06 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 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:24:06 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 05:24:06 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 05:24:06 INFO - Using env: {'DISPLAY': ':0', 05:24:06 INFO - 'HOME': '/home/cltbld', 05:24:06 INFO - 'LANG': 'en_US.UTF-8', 05:24:06 INFO - 'LANGUAGE': 'en_US:en', 05:24:06 INFO - 'LOGNAME': 'cltbld', 05:24:06 INFO - 'MAIL': '/var/mail/cltbld', 05:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:06 INFO - 'MOZ_NO_REMOTE': '1', 05:24:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:06 INFO - 'NO_EM_RESTART': '1', 05:24:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:06 INFO - 'PWD': '/builds/slave/test', 05:24:06 INFO - 'SHELL': '/bin/bash', 05:24:06 INFO - 'SHLVL': '1', 05:24:06 INFO - 'TERM': 'linux', 05:24:06 INFO - 'TMOUT': '86400', 05:24:06 INFO - 'USER': 'cltbld', 05:24:06 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:06 INFO - '_': '/tools/buildbot/bin/python'} 05:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:06 INFO - Downloading/unpacking jsonschema 05:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:24:08 INFO - Downloading/unpacking functools32 (from jsonschema) 05:24:08 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 05:24:08 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 05:24:08 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 05:24:08 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 05:24:08 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 05:24:08 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 05:24:08 INFO - Downloading functools32-3.2.3-2.tar.gz 05:24:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:24:08 INFO - warning: no files found matching '*.txt' 05:24:08 INFO - no previously-included directories found matching 'build' 05:24:08 INFO - no previously-included directories found matching 'dist' 05:24:08 INFO - no previously-included directories found matching '.git*' 05:24:08 INFO - Installing collected packages: jsonschema, functools32 05:24:08 INFO - Running setup.py install for functools32 05:24:08 INFO - warning: no files found matching '*.txt' 05:24:08 INFO - no previously-included directories found matching 'build' 05:24:08 INFO - no previously-included directories found matching 'dist' 05:24:08 INFO - no previously-included directories found matching '.git*' 05:24:08 INFO - Successfully installed jsonschema functools32 05:24:08 INFO - Cleaning up... 05:24:08 INFO - Return code: 0 05:24:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:08 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 0x7fb9cd18df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bf48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cc07d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, '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': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', '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 05:24:08 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 05:24:08 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 05:24:08 INFO - Using env: {'DISPLAY': ':0', 05:24:08 INFO - 'HOME': '/home/cltbld', 05:24:08 INFO - 'LANG': 'en_US.UTF-8', 05:24:08 INFO - 'LANGUAGE': 'en_US:en', 05:24:08 INFO - 'LOGNAME': 'cltbld', 05:24:08 INFO - 'MAIL': '/var/mail/cltbld', 05:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:08 INFO - 'MOZ_NO_REMOTE': '1', 05:24:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:08 INFO - 'NO_EM_RESTART': '1', 05:24:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:08 INFO - 'PWD': '/builds/slave/test', 05:24:08 INFO - 'SHELL': '/bin/bash', 05:24:08 INFO - 'SHLVL': '1', 05:24:08 INFO - 'TERM': 'linux', 05:24:08 INFO - 'TMOUT': '86400', 05:24:08 INFO - 'USER': 'cltbld', 05:24:08 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:08 INFO - '_': '/tools/buildbot/bin/python'} 05:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:09 INFO - Downloading/unpacking flake8 05:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:24:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:24:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:24:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:24:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:24:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:24:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:24:11 INFO - no previously-included directories found matching 'docs/_build' 05:24:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:24:11 INFO - Running setup.py install for pep8 05:24:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:24:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:24:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:24:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:24:11 INFO - no previously-included directories found matching 'docs/_build' 05:24:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:24:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:24:11 INFO - Cleaning up... 05:24:11 INFO - Return code: 0 05:24:11 INFO - Running post-action listener: _resource_record_post_action 05:24:11 INFO - Running post-action listener: _start_resource_monitoring 05:24:11 INFO - Starting resource monitoring. 05:24:11 INFO - ##### 05:24:11 INFO - ##### Running install step. 05:24:11 INFO - ##### 05:24:11 INFO - Running pre-action listener: _resource_record_pre_action 05:24:11 INFO - Running main action method: install 05:24:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:12 INFO - Reading from file tmpfile_stdout 05:24:12 INFO - Detecting whether we're running mozinstall >=1.0... 05:24:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:24:12 INFO - Reading from file tmpfile_stdout 05:24:12 INFO - Output received: 05:24:12 INFO - Usage: mozinstall [options] installer 05:24:12 INFO - Options: 05:24:12 INFO - -h, --help show this help message and exit 05:24:12 INFO - -d DEST, --destination=DEST 05:24:12 INFO - Directory to install application into. [default: 05:24:12 INFO - "/builds/slave/test"] 05:24:12 INFO - --app=APP Application being installed. [default: firefox] 05:24:12 INFO - mkdir: /builds/slave/test/build/application 05:24:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:24:27 INFO - Reading from file tmpfile_stdout 05:24:27 INFO - Output received: 05:24:27 INFO - /builds/slave/test/build/application/firefox/firefox 05:24:27 INFO - Running post-action listener: _resource_record_post_action 05:24:27 INFO - ##### 05:24:27 INFO - ##### Running run-tests step. 05:24:27 INFO - ##### 05:24:27 INFO - Running pre-action listener: _resource_record_pre_action 05:24:27 INFO - Running main action method: run_tests 05:24:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:27 INFO - Python 2.7.3 05:24:27 INFO - Return code: 0 05:24:27 INFO - grabbing minidump binary from tooltool 05:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d4f640>, '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 0x2d50540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d509d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:24:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:24:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:28 INFO - Return code: 0 05:24:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:24:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:24:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:24:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:24:29 INFO - Return code: 0 05:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:24:29 INFO - Using env: {'DISPLAY': ':0', 05:24:29 INFO - 'HOME': '/home/cltbld', 05:24:29 INFO - 'LANG': 'en_US.UTF-8', 05:24:29 INFO - 'LANGUAGE': 'en_US:en', 05:24:29 INFO - 'LOGNAME': 'cltbld', 05:24:29 INFO - 'MAIL': '/var/mail/cltbld', 05:24:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:29 INFO - 'MOZ_NO_REMOTE': '1', 05:24:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:29 INFO - 'NO_EM_RESTART': '1', 05:24:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:29 INFO - 'PWD': '/builds/slave/test', 05:24:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:29 INFO - 'SHELL': '/bin/bash', 05:24:29 INFO - 'SHLVL': '1', 05:24:29 INFO - 'TERM': 'linux', 05:24:29 INFO - 'TMOUT': '86400', 05:24:29 INFO - 'USER': 'cltbld', 05:24:29 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470', 05:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:29 INFO - '_': '/tools/buildbot/bin/python'} 05:24:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:24:29 INFO - mozversion application_buildid: 20160422035423 05:24:29 INFO - mozversion application_changeset: 0361b7030d363459416d46e651a8c50c9536788f 05:24:29 INFO - mozversion application_display_name: Nightly 05:24:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:29 INFO - mozversion application_name: Firefox 05:24:29 INFO - mozversion application_remotingname: firefox 05:24:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:24:29 INFO - mozversion application_vendor: Mozilla 05:24:29 INFO - mozversion application_version: 48.0a1 05:24:29 INFO - mozversion platform_buildid: 20160422035423 05:24:29 INFO - mozversion platform_changeset: 0361b7030d363459416d46e651a8c50c9536788f 05:24:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:24:29 INFO - mozversion platform_version: 48.0a1 05:24:29 INFO - using testdate: 1461587069 05:24:29 INFO - actual date: 1461587069 05:24:29 INFO - starting webserver on 'localhost:35321' 05:24:29 INFO - SUITE-START | Running 2 tests 05:24:29 INFO - TEST-START | dromaeo_css 05:24:29 INFO - operating with platform_type : linux_ 05:24:29 INFO - Initialising browser for dromaeo_css test... 05:24:29 INFO - TEST-INFO | started process 19580 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9coUZZ/profile http://localhost:35321/getInfo.html) 05:24:33 INFO - PROCESS | 19580 | 05:24:33 INFO - PROCESS | 19580 | (process:19648): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:24:34 INFO - PROCESS | 19580 | __metrics Screen width/height:1600/1200 05:24:34 INFO - PROCESS | 19580 | colorDepth:24 05:24:34 INFO - PROCESS | 19580 | Browser inner width/height: 1024/697 05:24:34 INFO - PROCESS | 19580 | __metrics 05:24:35 ERROR - PROCESS | 19580 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:24:36 INFO - TEST-INFO | 19580: exit 0 05:24:36 INFO - Browser initialized. 05:24:36 INFO - Running cycle 1/1 for dromaeo_css test... 05:24:36 INFO - TEST-INFO | started process 19700 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9coUZZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:38 INFO - PROCESS | 19700 | 05:24:38 INFO - PROCESS | 19700 | (process:19760): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:24:48 INFO - PROCESS | 19700 | RSS: Main: 165257216 05:24:48 INFO - PROCESS | 19700 | 05:25:37 INFO - PROCESS | 19700 | 1461587137637 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] 05:27:49 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:35321/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35321/tests/dromaeo/cssquery-ext.html) 05:27:50 INFO - PROCESS | 19700 | RSS: Main: 169205760 05:27:50 INFO - PROCESS | 19700 | 05:30:52 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:35321/tests/dromaeo/cssquery-ext.html (next: http://localhost:35321/tests/dromaeo/cssquery-jquery.html) 05:30:52 INFO - PROCESS | 19700 | RSS: Main: 166469632 05:30:52 INFO - PROCESS | 19700 | 05:33:54 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:35321/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35321/tests/dromaeo/cssquery-mootools.html) 05:33:55 INFO - PROCESS | 19700 | RSS: Main: 167596032 05:33:55 INFO - PROCESS | 19700 | 05:36:57 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:35321/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35321/tests/dromaeo/cssquery-prototype.html) 05:36:57 INFO - PROCESS | 19700 | RSS: Main: 165117952 05:36:57 INFO - PROCESS | 19700 | 05:40:00 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:35321/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35321/tests/dromaeo/cssquery-yui.html) 05:40:01 INFO - PROCESS | 19700 | RSS: Main: 166408192 05:40:01 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | Cycle 1(1): loaded http://localhost:35321/tests/dromaeo/cssquery-yui.html (next: http://localhost:35321/tests/dromaeo/cssquery-dojo.html) 05:43:03 INFO - PROCESS | 19700 | RSS: Main: 168300544 05:43:03 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | __start_tp_report 05:43:03 INFO - PROCESS | 19700 | _x_x_mozilla_page_load 05:43:03 INFO - PROCESS | 19700 | _x_x_mozilla_page_load_details 05:43:03 INFO - PROCESS | 19700 | |i|pagename|runs| 05:43:03 INFO - PROCESS | 19700 | |0;dojo.html;2643.762061414473;2708.21461776085;2713.59296105584;2747.423041161356;2747.835129892208;4648.3027545868135;4715.929261061078;4725.291206319045;4764.475907650156;4782.736949467778;4666.883327916796;4811.615070794337;4826.1554228010045;4886.560209581137;4899.338589290444;4407.625351845097;4423.823047078121;4492.326151077332;4493;4511.368408422823;539.3744945319503;4143.054905436763;4317.266064768981;4436.0906014267;4447.866564003069;4497.527759585229;4500.257457519506;4506.211413002744;4523.321501774743;4567.15507631086;4503.662225333081;4519.887002824923;4521.977390113061;4524.434445694288;4556.908861822778;4356.499002614708;4423.049044455457;4484.125595508877;4484.461864576239;4522.050369422425;4682.929756053662;4696.53034696531;4719.9292010619865;4738.573528382462;4743.383360163191;4970.90058198834;5197.766100525486;5211.358422097065;5213.218017297436;5273.2617433559335;5107.3105130807235;5140.203268493389;5140.845774626767;5183.377994640629;5201.141811601081;4784.521547845223;4819.975900120477;4823.2282834746275;4830.468648448667;4866.245731911524;5160.432352441228;5258.631895767336;5282.286891269706;5297.284866543045;5301.893962120736;5100.464451232641;5111.977604479119;5121.820736274212;5140.948590514122;5161.509656582619;5191.558717508979;5191.896162076738;5193.272941788153;5205.9219111713355;5250.133727934848;5026.170681837456;5078;5129.256257842592;5150;5158.09733296679;5025.773840177201;5060.84817455477;5063.620228482879;5071.46749591295;5106.821261255838;4283.7001409901;4284.978575107105;4288.399624052635;4328.285832837547;4336.869893903188;3719.888403347904;3783.9243215136094;3785.848566057327;3846.0577158596325;3886.7473614214987;4295;4393.7363758174615;4413.668974826836;4433.7118087324225;4443.133588950123;5191.195364718475;5235.345581802275;5235.73821308933;5238.109521381374;5238.5547228486;4335.067960388468;4449.555044495524;4497.797599108048;4507.256302710016;4544.068465964469;4575.496695363508;4633.536646335339;4648.953985353336;4668.4631267404;4672;4785.904281914342;4847.878803029953;4860.441049279237;4872.171730805842;4917.164082106122;5029.170186919117;5148.227765835155;5176.818811341735;5233.7383130842545;5245.029669511153;4851.248056551241;4859.246816743411;4875.756212189305;4877.829275975324;4910.361652985089;5110.9233361500355;5117.793640235541;5129.666571672829;5131.538161565477;5143.228515722672;5166.354205724285;5173.948260517347;5203.19350500673;5228.163493840967;5259.973700131475;5028.472010438997;5048.469910659328;5065.620078494201;5067.214581739836;5068.746562671926;4820.758962051954;5014.6991180529285;5024.824131155392;5044.697318160922;5045.268436076822;4975.4278258000795;4995.325631039763;5013.548780609763;5013.573846223112;5064.82273120439;5117.539421451938;5127.41034781005;5135.998480296306;5139.074966506065;5187.1181899078;4845.927311090266;4878.853634390974;4963.900721985612;5005.524475174852;5005.899882002413 05:43:03 INFO - PROCESS | 19700 | |1;ext.html;570.7003822992929;598.8772301678157;598.9071693887447;625.1279465146122;642.694720007996;34454.138646533815;35726.64273357269;35937.281254374895;35965.46051809225;35982;36049.0987725307;36336.63663363366;36440.08899777507;36512.452313215326;36585.45121823175;7488.326050655426;7659.846803063935;7671.271229233242;7681.231876812302;7686.961565192194;7549.811254718621;7759.689612415496;7787.571683557402;7789.454738168331;7832.804179895491;7690.077190737119;7722.652480638357;7734.1105772836;7759.146493885668;7774.300312971832;7749.496282741632;7754.7673569793;7796.142424333347;7816.3746900248125;7840.764777056697;7601.125870524905;7636.236376362319;7640;7643.732469363601;7650.19672934345;29775.851120744257;36339.27321453556;36666;36792.63207367946;36960.815195924115;2689.784817214618;2741.575055866344;3255.5279484474618;3328.8169150696704;3483.5993860705835;25618;26341.07806226773;26571.601425978624;27030.864845675645;27640.58539121915;3391.576052993376;3425.486177073434;3432.0733401981574;3508.6842184203288;3538.663826936437;211619;211633;215606;216110;216245;49859.25211121836;50425;50665.74667126641;50700.23949640759;50713;57931;58967;59085.70457147687;59199;59253;13317.533886313933;13528.932355338357;13546.729065418833;13590.252536110609;13621.931890340684;9356.672516461891;9412.858807117898;9487.33588648788;9525.761855953519;9631.373960692672;17507.212175452136;17761.822381776015;17809.376671816684;18505.444836654922;18658.53353666142;6748.138951630205;6781.220159681601;6884.51808373419;7016.508844380946;7052.506324557232;907.9273658107336;910.5765818894114;965.5317171171976;985.4530735441755;1047.36634336227;26638.06766763154;27384.58923116155;27541.173764787087;27548.862255688593;28114.71885281162;937.5452905340904;952.618952419024;960.1646567486332;1001.4892404873567;1088.7985722641338;13196.868031319564;13596.59210223695;13697.794533082011;13816.516421925184;13844.792328115087;348344;351333;351631;351964;354879;48724;49736.75131624391;49824;49978.50021499811;50072.74963625159;1191.2078467818976;1225;1250.4998000799571;1273.7834568123442;1275.1583954589926;25313.493730124927;26203.868980654974;26456.75117614947;26672.7332726678;26797.464050719267;40441;41186.79406602948;41385.37921931113;41509.169816602895;41514.58485415228;334717;335179;335453;338318;338866;24805.50388992246;24892.626610600495;25556.488870222864;25650.230493085237;25721.614175787003;40265.59734402619;41469.585304146574;41601.79199103985;41709.37435938524;41950;34533.44599493079;35286.82356588303;35328;35515;35538;34346.14134646554;35351.823240883634;35374.29251415009;35411.82294088513;35462.45375462536;35117;35405.82297088498;36130.45804312885;37187;37239.25521489609;27738.86130569415;29699.554506681983;29854.25364365908;30655;32291.35417291594;23761.64357534604;24478.50892736794;24635.50728985446;24653.87673061695;24832.255032349058 05:43:03 INFO - PROCESS | 19700 | |2;jquery.html;382.47409811509175;382.60540330495337;385.31029457271484;394.27453485586517;402.6818813137621;15154.92422537887;16213.18934053297;16226.51320460385;16244.593885152877;16254.593635159126;15511.301991410386;16019.19904004798;16726.9127506712;16876.409325673572;16891.57771055724;5031.345925029741;5175.948240517584;5207.765650545734;5209.557187639055;5210.5310522053005;5016.774245158958;5112.923306150411;5117.13008788505;5183.429822719517;5200.531952124308;5077.898442031157;5085;5112.36095488064;5123.641345105844;5130.6152038597065;4863.124637565237;5061.620378471629;5133.3326667533165;5138.640295179339;5159.380874295071;4901.436334821506;4955.4796746341835;4960;4967.627427942919;4976.402831660224;15703.921480392524;17471.21379537911;20110.095045722832;20130.69803952942;20185;1890.6691329017367;1893.8200870917242;1918.3189967561455;1946.8929208893505;2304.9654255186188;8773.605187766565;9364.157225849638;9766.511674416251;10644.308119972255;13254.204747715168;1733.843954044142;1736.314155908409;1792.2382987230505;1801.1714611278828;1817.0006496427045;69695.65152174258;70271.64864175646;70333;70738.29261707369;71312.64343678301;10062;10084;10156.23828212887;10276.177905767521;10472.947635261928;13930.37313320903;13988.58034258974;14014.649633759238;14020.158790472604;14062.22657753813;6587.275399706028;6824.522283440211;6950.374466298057;7018.508704390643;7279.381252593483;6728.192616885907;6790.219124800612;6807.693653785592;6882.8967565486555;6925.099737034153;4367.563243675607;4375.921437317249;4414.3157810539415;4452.666050046259;4535.750533720611;1865.7947625761426;1885.151681743221;1902.2295970132118;1904.5048287445363;1932.1498540642071;773.9264769846856;815.8653822119284;845.9111793261742;889.7953470701775;957.0381766324799;16240.75638865418;16488.587785305463;16544.090075045988;16642.16789160537;17129.314827406764;884.7699598104539;910.3445519226146;914.6387177065022;938.3009657804979;947.9857802132974;6931.4108300795;9185.632574696938;9279.211267042241;9590.232781377474;9612.471314077653;394138;400980;408500;408719;410368;55076.449235507935;69085.65457172682;76314;78388;79312.20687793163;960.5869476125333;961.778790878102;989.5843745626854;1002.1231422505308;1007.1791489935679;15190.544283671508;15293.311800969206;15326.233688315762;15367.615809604402;15800.288986995383;33528;33824.323513530086;34143.65856341504;34738.305233895684;34824;46357;46838;47519.524804752895;48039.279410808165;48341.758291208316;7729.304362607392;7772.533647981139;7796.064472263365;7832.216778322122;7855.960720196363;10959.287646302964;11481.540738370371;11659.708507287385;11728.179027467995;11790.292582445081;9980.351277166961;10085;10117.392956422638;10195.796084078425;10371.844422333521;9923.1069203772;10124.038216369434;10157;10170.644027459004;10275.075243228146;9865.408075515492;10151.695449136538;10540.262181647211;10630.893691062991;10641.680749577525;7767.495112817649;7817.960910195413;7862.253085956825;7940.761777146695;8008.199180081945;9761.56072976732;10093.444860532742;10193.082622564005;10193.23550733683;10253.538590763583 05:43:03 INFO - PROCESS | 19700 | |3;mootools.html;1440.9783853242204;1474.056603773585;1571.7328054230786;1743.5989722363863;1775.7425173349873;6956.582605043688;7566.432517561184;7576.507527010741;7884.724034658788;7911.723089691862;7439.851202975938;7528.623568821565;7541.245875412456;7613.581253031081;7626.351760100384;2258.7402448718403;2313.201945328867;2313.560423519534;2314.085936055257;2319.7564255753155;2269.977300226993;2270.318904328703;2288.1533832481923;2303.6314189729646;2304.608216603173;2255.9323220303418;2262.5248697773486;2281.2243837095384;2337.497438050819;2340.742518322983;2241.3051953894346;2244.5847518209102;2248.6289762189217;2249.6288112461425;2251.4258863989576;2191.813695835856;2195.5169862630196;2217.15748015753;2220.04538048639;2226.220822712054;7859.842803143963;7966.402519811038;7987.800304992364;8004.719834805812;8010.198980102002;4724.645651576111;4850.369451971256;4863.951360486385;4865.464798872121;4873.585745211662;2867.1828528869382;2921.576371426131;3023.1535170152392;3067.8312692801887;3078.2612173078523;978.7210644966221;983.9803203935882;988.6836212412031;989.4409658542972;1007.9092881640614;10746.301490403177;10953.288036277696;10972.122230221563;10982.835257471144;11041.558337666484;4341.413909122228;4362.280223763107;4525.298578720303;4552.294394368878;4635.374224479652;9343.205827504602;9362;9370.29722770795;9381.859272110923;9400.811983760286;4868;4901.730404827768;4917.286993385939;4921.384826896638;4922.532359425849;3763.41667041609;4253.532111467736;4368.584984426474;4408.426904502394;4472.217361961643;3845.8653947111716;3864.574896761354;3907.531096268466;4019.397090436458;4030.6775457963295;1254.4856608790353;1288.2656885575132;1295.3199570225518;1298.6688394459352;1311.1608570514877;670.8624731929942;766.4596459496042;813.2071230550166;821.4373154389262;822.7202751064547;5282.260483532308;5343.3587969443915;5384.434634363413;5449;5529.253550770675;420.9515905670826;427.7625917615753;434.48947486702997;437.9299312110111;438.7257963772642;3585.390483617739;3586.228960773446;3646.343658141507;3650.0327413235514;3686.096906257984;9610.087041731025;9806.215502759762;9949;9985.20118390527;9985.45080020606;9755.12203901638;9801.705948821547;9818.116369526777;9829.901700983042;9831.016898310254;6897.137857767779;7272.927270727331;7463.776086717407;7488.887666685004;7534.756765133801;5363.490468405495;5450.155225939986;5481.890362192814;5555.527780138572;5606;8994.775130621787;9042.231410330196;9069.455832650063;9088.636454541744;9161.31290153228;9054.637814487347;9129.269658427444;9308.650245714198;9370.531473426438;9390.295727820578;6135.294441139326;6556.377144171296;6638.236602790547;6680.231773346128;6773.93226067733;7273.599952002691;7301.853962920818;7323.59720215372;7332.450066244946;7383.852322953403;6566.737330506726;6777.356151165632;6796.626185559644;6823.4882383822405;6947.340002699735;6561.442277406283;6607.735690572324;6671.366220209073;6696.062551242927;6712.160979877515;6282.654454005074;6340.461060809698;6353.36466353361;6355.777547785805;6366.013267943476;4394.912101758011;4532;4619.3994780678295;4657.510961349009;4666.8133274668635;4542.273236282179;4557.270836666117;4565.3836732040745;4613.469451013177;4633.0039041606215 05:43:03 INFO - PROCESS | 19700 | |4;prototype.html;307.7691731201599;309.57124382729916;320.3944544810309;320.8235470491229;322.87730662348304;5241.0828105081555;5242.108841496945;5397.568194544437;5482.780688772449;5494.505494505489;5239.921401178975;5332.546733527645;5349.438308977559;5358.24984502169;5368.489993450626;4219.30381487055;4226.682998775089;4233.724807887477;4236.237477254093;4241.897106752252;4029.37544680575;4216.494020717518;4239;4239.618434340909;4241.448611680477;4168.416421700964;4199.286121359376;4205.894852628693;4207.0113523322;4214.283571792803;4211.220924129046;4214.91570168598;4220.641245494121;4242.8727138185595;4247.660187185049;3741.214344987529;3747.6627103560536;3750.5686846012786;3754.7559408638626;3764.0452244987828;5055.115354812893;5148.922766158511;5163.251328557376;5215.530602245778;5226.1638137898135;4789.784459699322;4843.4187897452175;4851.199552073904;4858.5384388483035;4879.1461494238365;5523.762876035212;5686.317641883001;5706.629069110536;5710.343310519302;5712.285964254468;4049.3925911113274;4062.979685101556;4073.307537718595;4073.551909289976;4076.8369265229353;5823.184754134425;5946.672932988684;5959.374265702126;5967.7911273105665;5981.58128930975;4172.561881002451;4179.868267183758;4181.979090104591;4186.079062606222;4195.97902010488;4022.5374081980362;4258.765767882796;4263.616274535307;4268.466441694788;4279.12277983019;4819.3012013257885;4830.420349558076;4844.467108618049;4847.95152048475;4851.684640498427;5425.647332923348;5750.1949727038245;5771.538276937835;5788.88422231553;5798.536117110621;1429.578274409031;1480.2154857925316;1480.8445113263167;1484.1021182184838;1484.4433337498438;986.4919566423155;989.4607438945792;1004.3923426327113;1018.3889666200221;1024.1499555369026;4926.162552366106;4933.519944016781;4938.506149385104;4943.233798761198;4951.10880041596;5856.970715146482;5871.706414679249;5872.2366092408565;5876.882462350814;5905.498032667186;4809.687370320918;4897.261472177397;4925.950740492549;4930.654854160174;4935.7532123393685;3150.5116706910007;3161.103337997183;3162.683731626819;3173.8730450782173;3181;5456;5479.4520547945685;5482.314710661168;5486.972565137228;5494.450554944554;5762.538996880324;5990.430909063632;5999.940000600025;6015.157877897098;6019.398060194033;5433.130699088127;5458.2904222450825;5469.1249400096585;5484.643498172606;5514.80698175562;5624.578156638187;5904.557158213065;5904.61619994699;5916.852078698067;5968.612040217372;5845.386234445322;5851.385604511635;5853.94146058534;5858.5898987072205;5893.233879595625;5544.099657987433;5544.140658197985;5549.417311182429;5560.638558493685;5576.386597474356;3958.477923296927;4029.3553031514816;4098.590140985878;4099.1801639671585;4106.486689163855;5626.971865140648;5716.342620598518;5903.793367232298;5934.7922822701;5938.376470470538;5723.513501352431;5729.427057294237;5755.97122014404;5758.568107391879;5758.869218389223;5700.401457846866;5742.655440673406;5753.813550850818;5773.863670441963;5779.508741756998;5506.91739623898;5561.416051314553;5564.582656300713;5619.606627536034;5636.351819540805;5733.480627633764;5745.2818397700285;5769.365369809234;5773.7979170730505;5799.188113664006;5472.835814925558;5577.2191893135805;5581.795190899653;5598.496135347838;5617.601351182476 05:43:03 INFO - PROCESS | 19700 | |5;yui.html;724.1418918581483;725.5066554742773;731.6890321613315;763.2256746725651;766.2184571736829;11876.52493900244;11936.462859171337;11961.162718609701;11996;12044.096692748035;10586.841197382046;11761.588344407948;11809.940950295259;11861.822072668896;11951;769.1270408086834;792.1009654042674;792.9167437419075;800.4836880212259;800.5316889619579;781.8358144789601;783.4907310248356;785.5640119733546;788.9921100788976;790.4545863354296;824.8556502612049;825.8719898415756;826.7809030606894;826.9421140520166;830.2610676497922;819.270848944434;820.7168526858254;821.7493664432376;822.4530687093076;825.1171246765963;599.3616798109999;600.7506884642868;602.0878369270558;603.6287683074877;603.8520562462181;13063.542776002889;13101.934490327487;13146.802797958038;13153.736925261537;13221.471141154343;12311.507539698401;12461.501539938483;12599.244045357307;12611.09944712712;12723.23660580368;9230.446173229628;9288.814223715555;9441.575129119206;9509.952450237774;9624.230061595055;3292.539044533767;3300.2574420755377;3302.53764472974;3314.9668503315143;3323.302106557626;46207;46345.53654463446;46493.30260046102;46586.76706616479;46692.29961550579;9227.953860230657;9286.67496637628;9298.814023719622;9472.763071538;9521.095495927875;17223.56941076458;17224.224909879085;17233.655326893644;17234.138293085296;17255;6819.7272109116075;6904.3095690431555;6987.336203060803;7048.048513450721;7321.524100933422;3806.08653923062;3897.7466464679705;3904.590018048121;3912.1784425270444;3949.4470774091074;2931.7068293170514;2981.6272965879266;2987.9402411951637;2996.550517422404;3005.3538489224484;475.7835184990821;498.27003439960635;501.63882004957105;532.4595535531439;559.4489427913502;455.41251785197386;521.9373675159005;529.0239508107535;566.3684991234826;591.1753104419397;6705.564138330993;7009.894851577231;7096.183938846995;7235.638218089074;7364.484486086029;453.4603821452461;490.279289444516;506.9847904562869;534.7860855657768;575.0782964661735;3472.461768425898;3548.3258180945536;3559.394902866519;3667.3582123128344;3808.619138086169;50296.49703502991;50370;50476.24285635718;50502.49497505051;50990;45291.09417811691;45333;45339.54660453419;45512;46125.76937115293;40160.196796063916;41262.58737412647;41973;42746.35880461796;43376.3493547597;6905.309469053054;7027.9297207027275;7120.715171393086;7259.818504537428;7380.852382952418;28118.297042573282;28634;28857.56713649255;29056.12831615055;29373.999999999145;34264.31471370509;34576.82711586426;37014;37285;37367.25265494729;8852.739896600198;8943.865842012505;8974.41666291689;9123.817523649357;9202.861957070516;9498.335116541775;9868.210543156387;9974.102330790263;9982.550785214538;9984.17264754779;9180.311476639145;9218.400803947721;9228;9323.673671421468;9329.673461428818;9729.124378805942;9770.658026969022;9812.26408019387;9821.066998635119;9904.702858914245;9253.67612133572;9276.443413395218;9280.443173409618;9393.295502837178;9427.858582121411;7840.725574604861;7969.282764551218;7988.440809143304;8034.799130021796;8321.583920804056;7845.293923546908;8136.796580085545;8145.104038555873;8263.547166798126;8291.08798032204 05:43:03 INFO - PROCESS | 19700 | __end_tp_report 05:43:03 INFO - PROCESS | 19700 | __start_cc_report 05:43:03 INFO - PROCESS | 19700 | _x_x_mozilla_cycle_collect,365 05:43:03 INFO - PROCESS | 19700 | __end_cc_report 05:43:03 INFO - PROCESS | 19700 | __startTimestamp1461588183971__endTimestamp 05:43:03 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | ------- Summary: start ------- 05:43:03 INFO - PROCESS | 19700 | Number of tests: 6 05:43:03 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | [#0] dojo.html Cycles:165 Average:4746.83 Median:4878.85 stddev:601.96 (12.3%) stddev-sans-first:580.75 05:43:03 INFO - PROCESS | 19700 | Values: 2643.8 2708.2 2713.6 2747.4 2747.8 4648.3 4715.9 4725.3 4764.5 4782.7 4666.9 4811.6 4826.2 4886.6 4899.3 4407.6 4423.8 4492.3 4493.0 4511.4 539.4 4143.1 4317.3 4436.1 4447.9 4497.5 4500.3 4506.2 4523.3 4567.2 4503.7 4519.9 4522.0 4524.4 4556.9 4356.5 4423.0 4484.1 4484.5 4522.1 4682.9 4696.5 4719.9 4738.6 4743.4 4970.9 5197.8 5211.4 5213.2 5273.3 5107.3 5140.2 5140.8 5183.4 5201.1 4784.5 4820.0 4823.2 4830.5 4866.2 5160.4 5258.6 5282.3 5297.3 5301.9 5100.5 5112.0 5121.8 5140.9 5161.5 5191.6 5191.9 5193.3 5205.9 5250.1 5026.2 5078.0 5129.3 5150.0 5158.1 5025.8 5060.8 5063.6 5071.5 5106.8 4283.7 4285.0 4288.4 4328.3 4336.9 3719.9 3783.9 3785.8 3846.1 3886.7 4295.0 4393.7 4413.7 4433.7 4443.1 5191.2 5235.3 5235.7 5238.1 5238.6 4335.1 4449.6 4497.8 4507.3 4544.1 4575.5 4633.5 4649.0 4668.5 4672.0 4785.9 4847.9 4860.4 4872.2 4917.2 5029.2 5148.2 5176.8 5233.7 5245.0 4851.2 4859.2 4875.8 4877.8 4910.4 5110.9 5117.8 5129.7 5131.5 5143.2 5166.4 5173.9 5203.2 5228.2 5260.0 5028.5 5048.5 5065.6 5067.2 5068.7 4820.8 5014.7 5024.8 5044.7 5045.3 4975.4 4995.3 5013.5 5013.6 5064.8 5117.5 5127.4 5136.0 5139.1 5187.1 4845.9 4878.9 4963.9 5005.5 5005.9 05:43:03 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | [#1] ext.html Cycles:180 Average:45240.76 Median:35526.50 stddev:80864.79 (227.6%) stddev-sans-first:81022.08 05:43:03 INFO - PROCESS | 19700 | Values: 570.7 598.9 598.9 625.1 642.7 34454.1 35726.6 35937.3 35965.5 35982.0 36049.1 36336.6 36440.1 36512.5 36585.5 7488.3 7659.8 7671.3 7681.2 7687.0 7549.8 7759.7 7787.6 7789.5 7832.8 7690.1 7722.7 7734.1 7759.1 7774.3 7749.5 7754.8 7796.1 7816.4 7840.8 7601.1 7636.2 7640.0 7643.7 7650.2 29775.9 36339.3 36666.0 36792.6 36960.8 2689.8 2741.6 3255.5 3328.8 3483.6 25618.0 26341.1 26571.6 27030.9 27640.6 3391.6 3425.5 3432.1 3508.7 3538.7 211619.0 211633.0 215606.0 216110.0 216245.0 49859.3 50425.0 50665.7 50700.2 50713.0 57931.0 58967.0 59085.7 59199.0 59253.0 13317.5 13528.9 13546.7 13590.3 13621.9 9356.7 9412.9 9487.3 9525.8 9631.4 17507.2 17761.8 17809.4 18505.4 18658.5 6748.1 6781.2 6884.5 7016.5 7052.5 907.9 910.6 965.5 985.5 1047.4 26638.1 27384.6 27541.2 27548.9 28114.7 937.5 952.6 960.2 1001.5 1088.8 13196.9 13596.6 13697.8 13816.5 13844.8 348344.0 351333.0 351631.0 351964.0 354879.0 48724.0 49736.8 49824.0 49978.5 50072.7 1191.2 1225.0 1250.5 1273.8 1275.2 25313.5 26203.9 26456.8 26672.7 26797.5 40441.0 41186.8 41385.4 41509.2 41514.6 334717.0 335179.0 335453.0 338318.0 338866.0 24805.5 24892.6 25556.5 25650.2 25721.6 40265.6 41469.6 41601.8 41709.4 41950.0 34533.4 35286.8 35328.0 35515.0 35538.0 34346.1 35351.8 35374.3 35411.8 35462.5 35117.0 35405.8 36130.5 37187.0 37239.3 27738.9 29699.6 29854.3 30655.0 32291.4 23761.6 24478.5 24635.5 24653.9 24832.3 05:43:03 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | [#2] jquery.html Cycles:180 Average:24313.61 Median:4371.74 stddev:66605.78 (1523.6%) stddev-sans-first:66768.39 05:43:03 INFO - PROCESS | 19700 | Values: 382.5 382.6 385.3 394.3 402.7 15154.9 16213.2 16226.5 16244.6 16254.6 15511.3 16019.2 16726.9 16876.4 16891.6 5031.3 5175.9 5207.8 5209.6 5210.5 5016.8 5112.9 5117.1 5183.4 5200.5 5077.9 5085.0 5112.4 5123.6 5130.6 4863.1 5061.6 5133.3 5138.6 5159.4 4901.4 4955.5 4960.0 4967.6 4976.4 15703.9 17471.2 20110.1 20130.7 20185.0 1890.7 1893.8 1918.3 1946.9 2305.0 8773.6 9364.2 9766.5 10644.3 13254.2 1733.8 1736.3 1792.2 1801.2 1817.0 69695.7 70271.6 70333.0 70738.3 71312.6 10062.0 10084.0 10156.2 10276.2 10472.9 13930.4 13988.6 14014.6 14020.2 14062.2 6587.3 6824.5 6950.4 7018.5 7279.4 6728.2 6790.2 6807.7 6882.9 6925.1 4367.6 4375.9 4414.3 4452.7 4535.8 1865.8 1885.2 1902.2 1904.5 1932.1 773.9 815.9 845.9 889.8 957.0 16240.8 16488.6 16544.1 16642.2 17129.3 884.8 910.3 914.6 938.3 948.0 6931.4 9185.6 9279.2 9590.2 9612.5 394138.0 400980.0 408500.0 408719.0 410368.0 55076.4 69085.7 76314.0 78388.0 79312.2 960.6 961.8 989.6 1002.1 1007.2 15190.5 15293.3 15326.2 15367.6 15800.3 33528.0 33824.3 34143.7 34738.3 34824.0 46357.0 46838.0 47519.5 48039.3 48341.8 7729.3 7772.5 7796.1 7832.2 7856.0 10959.3 11481.5 11659.7 11728.2 11790.3 9980.4 10085.0 10117.4 10195.8 10371.8 9923.1 10124.0 10157.0 10170.6 10275.1 9865.4 10151.7 10540.3 10630.9 10641.7 7767.5 7818.0 7862.3 7940.8 8008.2 9761.6 10093.4 10193.1 10193.2 10253.5 05:43:03 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | [#3] mootools.html Cycles:180 Average:5219.46 Median:5102.40 stddev:2911.61 (57.1%) stddev-sans-first:2905.93 05:43:03 INFO - PROCESS | 19700 | Values: 1441.0 1474.1 1571.7 1743.6 1775.7 6956.6 7566.4 7576.5 7884.7 7911.7 7439.9 7528.6 7541.2 7613.6 7626.4 2258.7 2313.2 2313.6 2314.1 2319.8 2270.0 2270.3 2288.2 2303.6 2304.6 2255.9 2262.5 2281.2 2337.5 2340.7 2241.3 2244.6 2248.6 2249.6 2251.4 2191.8 2195.5 2217.2 2220.0 2226.2 7859.8 7966.4 7987.8 8004.7 8010.2 4724.6 4850.4 4864.0 4865.5 4873.6 2867.2 2921.6 3023.2 3067.8 3078.3 978.7 984.0 988.7 989.4 1007.9 10746.3 10953.3 10972.1 10982.8 11041.6 4341.4 4362.3 4525.3 4552.3 4635.4 9343.2 9362.0 9370.3 9381.9 9400.8 4868.0 4901.7 4917.3 4921.4 4922.5 3763.4 4253.5 4368.6 4408.4 4472.2 3845.9 3864.6 3907.5 4019.4 4030.7 1254.5 1288.3 1295.3 1298.7 1311.2 670.9 766.5 813.2 821.4 822.7 5282.3 5343.4 5384.4 5449.0 5529.3 421.0 427.8 434.5 437.9 438.7 3585.4 3586.2 3646.3 3650.0 3686.1 9610.1 9806.2 9949.0 9985.2 9985.5 9755.1 9801.7 9818.1 9829.9 9831.0 6897.1 7272.9 7463.8 7488.9 7534.8 5363.5 5450.2 5481.9 5555.5 5606.0 8994.8 9042.2 9069.5 9088.6 9161.3 9054.6 9129.3 9308.7 9370.5 9390.3 6135.3 6556.4 6638.2 6680.2 6773.9 7273.6 7301.9 7323.6 7332.5 7383.9 6566.7 6777.4 6796.6 6823.5 6947.3 6561.4 6607.7 6671.4 6696.1 6712.2 6282.7 6340.5 6353.4 6355.8 6366.0 4394.9 4532.0 4619.4 4657.5 4666.8 4542.3 4557.3 4565.4 4613.5 4633.0 05:43:03 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | [#4] prototype.html Cycles:180 Average:4724.45 Median:5287.33 stddev:1371.12 (25.9%) stddev-sans-first:1334.29 05:43:03 INFO - PROCESS | 19700 | Values: 307.8 309.6 320.4 320.8 322.9 5241.1 5242.1 5397.6 5482.8 5494.5 5239.9 5332.5 5349.4 5358.2 5368.5 4219.3 4226.7 4233.7 4236.2 4241.9 4029.4 4216.5 4239.0 4239.6 4241.4 4168.4 4199.3 4205.9 4207.0 4214.3 4211.2 4214.9 4220.6 4242.9 4247.7 3741.2 3747.7 3750.6 3754.8 3764.0 5055.1 5148.9 5163.3 5215.5 5226.2 4789.8 4843.4 4851.2 4858.5 4879.1 5523.8 5686.3 5706.6 5710.3 5712.3 4049.4 4063.0 4073.3 4073.6 4076.8 5823.2 5946.7 5959.4 5967.8 5981.6 4172.6 4179.9 4182.0 4186.1 4196.0 4022.5 4258.8 4263.6 4268.5 4279.1 4819.3 4830.4 4844.5 4848.0 4851.7 5425.6 5750.2 5771.5 5788.9 5798.5 1429.6 1480.2 1480.8 1484.1 1484.4 986.5 989.5 1004.4 1018.4 1024.1 4926.2 4933.5 4938.5 4943.2 4951.1 5857.0 5871.7 5872.2 5876.9 5905.5 4809.7 4897.3 4926.0 4930.7 4935.8 3150.5 3161.1 3162.7 3173.9 3181.0 5456.0 5479.5 5482.3 5487.0 5494.5 5762.5 5990.4 5999.9 6015.2 6019.4 5433.1 5458.3 5469.1 5484.6 5514.8 5624.6 5904.6 5904.6 5916.9 5968.6 5845.4 5851.4 5853.9 5858.6 5893.2 5544.1 5544.1 5549.4 5560.6 5576.4 3958.5 4029.4 4098.6 4099.2 4106.5 5627.0 5716.3 5903.8 5934.8 5938.4 5723.5 5729.4 5756.0 5758.6 5758.9 5700.4 5742.7 5753.8 5773.9 5779.5 5506.9 5561.4 5564.6 5619.6 5636.4 5733.5 5745.3 5769.4 5773.8 5799.2 5472.8 5577.2 5581.8 5598.5 5617.6 05:43:03 INFO - PROCESS | 19700 | 05:43:03 INFO - PROCESS | 19700 | [#5] yui.html Cycles:180 Average:12199.24 Median:600.06 stddev:14163.21 (2360.3%) stddev-sans-first:14176.73 05:43:03 INFO - PROCESS | 19700 | Values: 724.1 725.5 731.7 763.2 766.2 11876.5 11936.5 11961.2 11996.0 12044.1 10586.8 11761.6 11809.9 11861.8 11951.0 769.1 792.1 792.9 800.5 800.5 781.8 783.5 785.6 789.0 790.5 824.9 825.9 826.8 826.9 830.3 819.3 820.7 821.7 822.5 825.1 599.4 600.8 602.1 603.6 603.9 13063.5 13101.9 13146.8 13153.7 13221.5 12311.5 12461.5 12599.2 12611.1 12723.2 9230.4 9288.8 9441.6 9510.0 9624.2 3292.5 3300.3 3302.5 3315.0 3323.3 46207.0 46345.5 46493.3 46586.8 46692.3 9228.0 9286.7 9298.8 9472.8 9521.1 17223.6 17224.2 17233.7 17234.1 17255.0 6819.7 6904.3 6987.3 7048.0 7321.5 3806.1 3897.7 3904.6 3912.2 3949.4 2931.7 2981.6 2987.9 2996.6 3005.4 475.8 498.3 501.6 532.5 559.4 455.4 521.9 529.0 566.4 591.2 6705.6 7009.9 7096.2 7235.6 7364.5 453.5 490.3 507.0 534.8 575.1 3472.5 3548.3 3559.4 3667.4 3808.6 50296.5 50370.0 50476.2 50502.5 50990.0 45291.1 45333.0 45339.5 45512.0 46125.8 40160.2 41262.6 41973.0 42746.4 43376.3 6905.3 7027.9 7120.7 7259.8 7380.9 28118.3 28634.0 28857.6 29056.1 29374.0 34264.3 34576.8 37014.0 37285.0 37367.3 8852.7 8943.9 8974.4 9123.8 9202.9 9498.3 9868.2 9974.1 9982.6 9984.2 9180.3 9218.4 9228.0 9323.7 9329.7 9729.1 9770.7 9812.3 9821.1 9904.7 9253.7 9276.4 9280.4 9393.3 9427.9 7840.7 7969.3 7988.4 8034.8 8321.6 7845.3 8136.8 8145.1 8263.5 8291.1 05:43:03 INFO - PROCESS | 19700 | -------- Summary: end -------- 05:43:03 INFO - PROCESS | 19700 | 05:43:04 INFO - TEST-INFO | 19700: exit 0 05:43:04 INFO - TEST-OK | dromaeo_css | took 1115476ms 05:43:04 INFO - TEST-START | kraken 05:43:04 INFO - operating with platform_type : linux_ 05:43:04 INFO - Initialising browser for kraken test... 05:43:04 INFO - TEST-INFO | started process 19932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAZXHvS/profile http://localhost:35321/getInfo.html) 05:43:08 INFO - PROCESS | 19932 | 05:43:08 INFO - PROCESS | 19932 | (process:20001): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:43:09 INFO - PROCESS | 19932 | __metrics Screen width/height:1600/1200 05:43:09 INFO - PROCESS | 19932 | colorDepth:24 05:43:09 INFO - PROCESS | 19932 | Browser inner width/height: 1024/697 05:43:09 INFO - PROCESS | 19932 | __metrics 05:43:10 ERROR - PROCESS | 19932 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:43:11 INFO - TEST-INFO | 19932: exit 0 05:43:11 INFO - Browser initialized. 05:43:11 INFO - Running cycle 1/1 for kraken test... 05:43:11 INFO - TEST-INFO | started process 20052 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAZXHvS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:13 INFO - PROCESS | 20052 | 05:43:13 INFO - PROCESS | 20052 | (process:20112): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:43:23 INFO - PROCESS | 20052 | RSS: Main: 164933632 05:43:23 INFO - PROCESS | 20052 | 05:43:25 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35321/tests/kraken/driver.html?testName=audio-beat-detection) 05:43:26 INFO - PROCESS | 20052 | RSS: Main: 168513536 05:43:26 INFO - PROCESS | 20052 | 05:43:29 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35321/tests/kraken/driver.html?testName=audio-dft) 05:43:30 INFO - PROCESS | 20052 | RSS: Main: 172146688 05:43:30 INFO - PROCESS | 20052 | 05:43:33 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35321/tests/kraken/driver.html?testName=audio-fft) 05:43:33 INFO - PROCESS | 20052 | RSS: Main: 164405248 05:43:33 INFO - PROCESS | 20052 | 05:43:35 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35321/tests/kraken/driver.html?testName=audio-oscillator) 05:43:36 INFO - PROCESS | 20052 | RSS: Main: 164474880 05:43:36 INFO - PROCESS | 20052 | 05:43:38 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35321/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:43:39 INFO - PROCESS | 20052 | RSS: Main: 164655104 05:43:39 INFO - PROCESS | 20052 | 05:43:42 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35321/tests/kraken/driver.html?testName=imaging-darkroom) 05:43:43 INFO - PROCESS | 20052 | RSS: Main: 165302272 05:43:43 INFO - PROCESS | 20052 | 05:43:46 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35321/tests/kraken/driver.html?testName=imaging-desaturate) 05:43:47 INFO - PROCESS | 20052 | RSS: Main: 165052416 05:43:47 INFO - PROCESS | 20052 | 05:43:50 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35321/tests/kraken/driver.html?testName=json-parse-financial) 05:43:50 INFO - PROCESS | 20052 | RSS: Main: 165834752 05:43:50 INFO - PROCESS | 20052 | 05:43:52 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35321/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:43:53 INFO - PROCESS | 20052 | RSS: Main: 165675008 05:43:53 INFO - PROCESS | 20052 | 05:43:55 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35321/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:43:55 INFO - PROCESS | 20052 | RSS: Main: 165404672 05:43:55 INFO - PROCESS | 20052 | 05:43:57 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35321/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:58 INFO - PROCESS | 20052 | RSS: Main: 166367232 05:43:58 INFO - PROCESS | 20052 | 05:44:01 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35321/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:44:01 INFO - PROCESS | 20052 | RSS: Main: 166506496 05:44:01 INFO - PROCESS | 20052 | 05:44:04 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35321/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:44:05 INFO - PROCESS | 20052 | RSS: Main: 166248448 05:44:05 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | Cycle 1(1): loaded http://localhost:35321/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35321/tests/kraken/driver.html?testName=ai-astar) 05:44:07 INFO - PROCESS | 20052 | RSS: Main: 168009728 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | __start_tp_report 05:44:07 INFO - PROCESS | 20052 | _x_x_mozilla_page_load 05:44:07 INFO - PROCESS | 20052 | _x_x_mozilla_page_load_details 05:44:07 INFO - PROCESS | 20052 | |i|pagename|runs| 05:44:07 INFO - PROCESS | 20052 | |0;ai-astar;114;109;112;114;113;125;113;114;113;114 05:44:07 INFO - PROCESS | 20052 | |1;audio-beat-detection;160;137;164;181;135;155;155;134;206;136 05:44:07 INFO - PROCESS | 20052 | |2;audio-dft;163;163;165;173;163;163;162;164;174;163 05:44:07 INFO - PROCESS | 20052 | |3;audio-fft;73;74;75;175;76;74;77;77;155;77 05:44:07 INFO - PROCESS | 20052 | |4;audio-oscillator;94;94;148;94;94;94;141;94;93;143 05:44:07 INFO - PROCESS | 20052 | |5;imaging-gaussian-blur;117;118;117;118;118;117;117;118;118;117 05:44:07 INFO - PROCESS | 20052 | |6;imaging-darkroom;150;148;147;149;147;149;146;148;148;147 05:44:07 INFO - PROCESS | 20052 | |7;imaging-desaturate;95;100;95;95;96;95;96;96;95;95 05:44:07 INFO - PROCESS | 20052 | |8;json-parse-financial;65;66;68;65;69;66;66;79;65;63 05:44:07 INFO - PROCESS | 20052 | |9;json-stringify-tinderbox;64;66;64;66;65;64;65;66;65;65 05:44:07 INFO - PROCESS | 20052 | |10;stanford-crypto-aes;79;88;80;79;79;80;81;81;82;82 05:44:07 INFO - PROCESS | 20052 | |11;stanford-crypto-ccm;140;135;143;139;140;134;136;136;170;137 05:44:07 INFO - PROCESS | 20052 | |12;stanford-crypto-pbkdf2;183;183;182;192;180;174;175;180;180;179 05:44:07 INFO - PROCESS | 20052 | |13;stanford-crypto-sha256-iterative;75;75;76;74;75;75;73;74;72;74 05:44:07 INFO - PROCESS | 20052 | __end_tp_report 05:44:07 INFO - PROCESS | 20052 | __start_cc_report 05:44:07 INFO - PROCESS | 20052 | _x_x_mozilla_cycle_collect,936 05:44:07 INFO - PROCESS | 20052 | __end_cc_report 05:44:07 INFO - PROCESS | 20052 | __startTimestamp1461588247658__endTimestamp 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | ------- Summary: start ------- 05:44:07 INFO - PROCESS | 20052 | Number of tests: 14 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:4.12 (3.6%) stddev-sans-first:4.37 05:44:07 INFO - PROCESS | 20052 | Values: 114.0 109.0 112.0 114.0 113.0 125.0 113.0 114.0 113.0 114.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#1] audio-beat-detection Cycles:10 Average:156.30 Median:157.50 stddev:23.31 (14.8%) stddev-sans-first:24.69 05:44:07 INFO - PROCESS | 20052 | Values: 160.0 137.0 164.0 181.0 135.0 155.0 155.0 134.0 206.0 136.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#2] audio-dft Cycles:10 Average:165.30 Median:163.50 stddev:4.40 (2.7%) stddev-sans-first:4.59 05:44:07 INFO - PROCESS | 20052 | Values: 163.0 163.0 165.0 173.0 163.0 163.0 162.0 164.0 174.0 163.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#3] audio-fft Cycles:10 Average:93.30 Median:75.50 stddev:38.11 (50.5%) stddev-sans-first:39.71 05:44:07 INFO - PROCESS | 20052 | Values: 73.0 74.0 75.0 175.0 76.0 74.0 77.0 77.0 155.0 77.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#4] audio-oscillator Cycles:10 Average:108.90 Median:94.00 stddev:24.28 (25.8%) stddev-sans-first:25.15 05:44:07 INFO - PROCESS | 20052 | Values: 94.0 94.0 148.0 94.0 94.0 94.0 141.0 94.0 93.0 143.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 05:44:07 INFO - PROCESS | 20052 | Values: 117.0 118.0 117.0 118.0 118.0 117.0 117.0 118.0 118.0 117.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:1.20 (0.8%) stddev-sans-first:1.00 05:44:07 INFO - PROCESS | 20052 | Values: 150.0 148.0 147.0 149.0 147.0 149.0 146.0 148.0 148.0 147.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:95.00 stddev:1.55 (1.6%) stddev-sans-first:1.62 05:44:07 INFO - PROCESS | 20052 | Values: 95.0 100.0 95.0 95.0 96.0 95.0 96.0 96.0 95.0 95.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:66.00 stddev:4.47 (6.8%) stddev-sans-first:4.67 05:44:07 INFO - PROCESS | 20052 | Values: 65.0 66.0 68.0 65.0 69.0 66.0 66.0 79.0 65.0 63.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.78 05:44:07 INFO - PROCESS | 20052 | Values: 64.0 66.0 64.0 66.0 65.0 64.0 65.0 66.0 65.0 65.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:2.69 (3.3%) stddev-sans-first:2.74 05:44:07 INFO - PROCESS | 20052 | Values: 79.0 88.0 80.0 79.0 79.0 80.0 81.0 81.0 82.0 82.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#11] stanford-crypto-ccm Cycles:10 Average:141.00 Median:139.50 stddev:10.55 (7.6%) stddev-sans-first:11.19 05:44:07 INFO - PROCESS | 20052 | Values: 140.0 135.0 143.0 139.0 140.0 134.0 136.0 136.0 170.0 137.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:181.00 stddev:4.96 (2.7%) stddev-sans-first:5.20 05:44:07 INFO - PROCESS | 20052 | Values: 183.0 183.0 182.0 192.0 180.0 174.0 175.0 180.0 180.0 179.0 05:44:07 INFO - PROCESS | 20052 | 05:44:07 INFO - PROCESS | 20052 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:75.00 stddev:1.16 (1.5%) stddev-sans-first:1.20 05:44:07 INFO - PROCESS | 20052 | Values: 75.0 75.0 76.0 74.0 75.0 75.0 73.0 74.0 72.0 74.0 05:44:07 INFO - PROCESS | 20052 | -------- Summary: end -------- 05:44:07 INFO - PROCESS | 20052 | 05:44:08 INFO - TEST-INFO | 20052: exit 0 05:44:08 INFO - TEST-OK | kraken | took 63955ms 05:44:08 INFO - SUITE-END | took 1179s 05:44:08 INFO - Completed test suite (00:19:39) 05:44:08 INFO - javascript benchmark 05:44:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2643.762061414473, 2708.21461776085, 2713.59296105584, 2747.423041161356, 2747.835129892208, 4648.3027545868135, 4715.929261061078, 4725.291206319045, 4764.475907650156, 4782.736949467778, 4666.883327916796, 4811.615070794337, 4826.1554228010045, 4886.560209581137, 4899.338589290444, 4407.625351845097, 4423.823047078121, 4492.326151077332, 4493.0, 4511.368408422823, 539.3744945319503, 4143.054905436763, 4317.266064768981, 4436.0906014267, 4447.866564003069, 4497.527759585229, 4500.257457519506, 4506.211413002744, 4523.321501774743, 4567.15507631086, 4503.662225333081, 4519.887002824923, 4521.977390113061, 4524.434445694288, 4556.908861822778, 4356.499002614708, 4423.049044455457, 4484.125595508877, 4484.461864576239, 4522.050369422425, 4682.929756053662, 4696.53034696531, 4719.9292010619865, 4738.573528382462, 4743.383360163191, 4970.90058198834, 5197.766100525486, 5211.358422097065, 5213.218017297436, 5273.2617433559335, 5107.3105130807235, 5140.203268493389, 5140.845774626767, 5183.377994640629, 5201.141811601081, 4784.521547845223, 4819.975900120477, 4823.2282834746275, 4830.468648448667, 4866.245731911524, 5160.432352441228, 5258.631895767336, 5282.286891269706, 5297.284866543045, 5301.893962120736, 5100.464451232641, 5111.977604479119, 5121.820736274212, 5140.948590514122, 5161.509656582619, 5191.558717508979, 5191.896162076738, 5193.272941788153, 5205.9219111713355, 5250.133727934848, 5026.170681837456, 5078.0, 5129.256257842592, 5150.0, 5158.09733296679, 5025.773840177201, 5060.84817455477, 5063.620228482879, 5071.46749591295, 5106.821261255838, 4283.7001409901, 4284.978575107105, 4288.399624052635, 4328.285832837547, 4336.869893903188, 3719.888403347904, 3783.9243215136094, 3785.848566057327, 3846.0577158596325, 3886.7473614214987, 4295.0, 4393.7363758174615, 4413.668974826836, 4433.7118087324225, 4443.133588950123, 5191.195364718475, 5235.345581802275, 5235.73821308933, 5238.109521381374, 5238.5547228486, 4335.067960388468, 4449.555044495524, 4497.797599108048, 4507.256302710016, 4544.068465964469, 4575.496695363508, 4633.536646335339, 4648.953985353336, 4668.4631267404, 4672.0, 4785.904281914342, 4847.878803029953, 4860.441049279237, 4872.171730805842, 4917.164082106122, 5029.170186919117, 5148.227765835155, 5176.818811341735, 5233.7383130842545, 5245.029669511153, 4851.248056551241, 4859.246816743411, 4875.756212189305, 4877.829275975324, 4910.361652985089, 5110.9233361500355, 5117.793640235541, 5129.666571672829, 5131.538161565477, 5143.228515722672, 5166.354205724285, 5173.948260517347, 5203.19350500673, 5228.163493840967, 5259.973700131475, 5028.472010438997, 5048.469910659328, 5065.620078494201, 5067.214581739836, 5068.746562671926, 4820.758962051954, 5014.6991180529285, 5024.824131155392, 5044.697318160922, 5045.268436076822, 4975.4278258000795, 4995.325631039763, 5013.548780609763, 5013.573846223112, 5064.82273120439, 5117.539421451938, 5127.41034781005, 5135.998480296306, 5139.074966506065, 5187.1181899078, 4845.927311090266, 4878.853634390974, 4963.900721985612, 5005.524475174852, 5005.899882002413], "value": 4710.015785351303, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.7003822992929, 598.8772301678157, 598.9071693887447, 625.1279465146122, 642.694720007996, 34454.138646533815, 35726.64273357269, 35937.281254374895, 35965.46051809225, 35982.0, 36049.0987725307, 36336.63663363366, 36440.08899777507, 36512.452313215326, 36585.45121823175, 7488.326050655426, 7659.846803063935, 7671.271229233242, 7681.231876812302, 7686.961565192194, 7549.811254718621, 7759.689612415496, 7787.571683557402, 7789.454738168331, 7832.804179895491, 7690.077190737119, 7722.652480638357, 7734.1105772836, 7759.146493885668, 7774.300312971832, 7749.496282741632, 7754.7673569793, 7796.142424333347, 7816.3746900248125, 7840.764777056697, 7601.125870524905, 7636.236376362319, 7640.0, 7643.732469363601, 7650.19672934345, 29775.851120744257, 36339.27321453556, 36666.0, 36792.63207367946, 36960.815195924115, 2689.784817214618, 2741.575055866344, 3255.5279484474618, 3328.8169150696704, 3483.5993860705835, 25618.0, 26341.07806226773, 26571.601425978624, 27030.864845675645, 27640.58539121915, 3391.576052993376, 3425.486177073434, 3432.0733401981574, 3508.6842184203288, 3538.663826936437, 211619.0, 211633.0, 215606.0, 216110.0, 216245.0, 49859.25211121836, 50425.0, 50665.74667126641, 50700.23949640759, 50713.0, 57931.0, 58967.0, 59085.70457147687, 59199.0, 59253.0, 13317.533886313933, 13528.932355338357, 13546.729065418833, 13590.252536110609, 13621.931890340684, 9356.672516461891, 9412.858807117898, 9487.33588648788, 9525.761855953519, 9631.373960692672, 17507.212175452136, 17761.822381776015, 17809.376671816684, 18505.444836654922, 18658.53353666142, 6748.138951630205, 6781.220159681601, 6884.51808373419, 7016.508844380946, 7052.506324557232, 907.9273658107336, 910.5765818894114, 965.5317171171976, 985.4530735441755, 1047.36634336227, 26638.06766763154, 27384.58923116155, 27541.173764787087, 27548.862255688593, 28114.71885281162, 937.5452905340904, 952.618952419024, 960.1646567486332, 1001.4892404873567, 1088.7985722641338, 13196.868031319564, 13596.59210223695, 13697.794533082011, 13816.516421925184, 13844.792328115087, 348344.0, 351333.0, 351631.0, 351964.0, 354879.0, 48724.0, 49736.75131624391, 49824.0, 49978.50021499811, 50072.74963625159, 1191.2078467818976, 1225.0, 1250.4998000799571, 1273.7834568123442, 1275.1583954589926, 25313.493730124927, 26203.868980654974, 26456.75117614947, 26672.7332726678, 26797.464050719267, 40441.0, 41186.79406602948, 41385.37921931113, 41509.169816602895, 41514.58485415228, 334717.0, 335179.0, 335453.0, 338318.0, 338866.0, 24805.50388992246, 24892.626610600495, 25556.488870222864, 25650.230493085237, 25721.614175787003, 40265.59734402619, 41469.585304146574, 41601.79199103985, 41709.37435938524, 41950.0, 34533.44599493079, 35286.82356588303, 35328.0, 35515.0, 35538.0, 34346.14134646554, 35351.823240883634, 35374.29251415009, 35411.82294088513, 35462.45375462536, 35117.0, 35405.82297088498, 36130.45804312885, 37187.0, 37239.25521489609, 27738.86130569415, 29699.554506681983, 29854.25364365908, 30655.0, 32291.35417291594, 23761.64357534604, 24478.50892736794, 24635.50728985446, 24653.87673061695, 24832.255032349058], "value": 17039.935644824076, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.47409811509175, 382.60540330495337, 385.31029457271484, 394.27453485586517, 402.6818813137621, 15154.92422537887, 16213.18934053297, 16226.51320460385, 16244.593885152877, 16254.593635159126, 15511.301991410386, 16019.19904004798, 16726.9127506712, 16876.409325673572, 16891.57771055724, 5031.345925029741, 5175.948240517584, 5207.765650545734, 5209.557187639055, 5210.5310522053005, 5016.774245158958, 5112.923306150411, 5117.13008788505, 5183.429822719517, 5200.531952124308, 5077.898442031157, 5085.0, 5112.36095488064, 5123.641345105844, 5130.6152038597065, 4863.124637565237, 5061.620378471629, 5133.3326667533165, 5138.640295179339, 5159.380874295071, 4901.436334821506, 4955.4796746341835, 4960.0, 4967.627427942919, 4976.402831660224, 15703.921480392524, 17471.21379537911, 20110.095045722832, 20130.69803952942, 20185.0, 1890.6691329017367, 1893.8200870917242, 1918.3189967561455, 1946.8929208893505, 2304.9654255186188, 8773.605187766565, 9364.157225849638, 9766.511674416251, 10644.308119972255, 13254.204747715168, 1733.843954044142, 1736.314155908409, 1792.2382987230505, 1801.1714611278828, 1817.0006496427045, 69695.65152174258, 70271.64864175646, 70333.0, 70738.29261707369, 71312.64343678301, 10062.0, 10084.0, 10156.23828212887, 10276.177905767521, 10472.947635261928, 13930.37313320903, 13988.58034258974, 14014.649633759238, 14020.158790472604, 14062.22657753813, 6587.275399706028, 6824.522283440211, 6950.374466298057, 7018.508704390643, 7279.381252593483, 6728.192616885907, 6790.219124800612, 6807.693653785592, 6882.8967565486555, 6925.099737034153, 4367.563243675607, 4375.921437317249, 4414.3157810539415, 4452.666050046259, 4535.750533720611, 1865.7947625761426, 1885.151681743221, 1902.2295970132118, 1904.5048287445363, 1932.1498540642071, 773.9264769846856, 815.8653822119284, 845.9111793261742, 889.7953470701775, 957.0381766324799, 16240.75638865418, 16488.587785305463, 16544.090075045988, 16642.16789160537, 17129.314827406764, 884.7699598104539, 910.3445519226146, 914.6387177065022, 938.3009657804979, 947.9857802132974, 6931.4108300795, 9185.632574696938, 9279.211267042241, 9590.232781377474, 9612.471314077653, 394138.0, 400980.0, 408500.0, 408719.0, 410368.0, 55076.449235507935, 69085.65457172682, 76314.0, 78388.0, 79312.20687793163, 960.5869476125333, 961.778790878102, 989.5843745626854, 1002.1231422505308, 1007.1791489935679, 15190.544283671508, 15293.311800969206, 15326.233688315762, 15367.615809604402, 15800.288986995383, 33528.0, 33824.323513530086, 34143.65856341504, 34738.305233895684, 34824.0, 46357.0, 46838.0, 47519.524804752895, 48039.279410808165, 48341.758291208316, 7729.304362607392, 7772.533647981139, 7796.064472263365, 7832.216778322122, 7855.960720196363, 10959.287646302964, 11481.540738370371, 11659.708507287385, 11728.179027467995, 11790.292582445081, 9980.351277166961, 10085.0, 10117.392956422638, 10195.796084078425, 10371.844422333521, 9923.1069203772, 10124.038216369434, 10157.0, 10170.644027459004, 10275.075243228146, 9865.408075515492, 10151.695449136538, 10540.262181647211, 10630.893691062991, 10641.680749577525, 7767.495112817649, 7817.960910195413, 7862.253085956825, 7940.761777146695, 8008.199180081945, 9761.56072976732, 10093.444860532742, 10193.082622564005, 10193.23550733683, 10253.538590763583], "value": 8141.021385338643, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.9783853242204, 1474.056603773585, 1571.7328054230786, 1743.5989722363863, 1775.7425173349873, 6956.582605043688, 7566.432517561184, 7576.507527010741, 7884.724034658788, 7911.723089691862, 7439.851202975938, 7528.623568821565, 7541.245875412456, 7613.581253031081, 7626.351760100384, 2258.7402448718403, 2313.201945328867, 2313.560423519534, 2314.085936055257, 2319.7564255753155, 2269.977300226993, 2270.318904328703, 2288.1533832481923, 2303.6314189729646, 2304.608216603173, 2255.9323220303418, 2262.5248697773486, 2281.2243837095384, 2337.497438050819, 2340.742518322983, 2241.3051953894346, 2244.5847518209102, 2248.6289762189217, 2249.6288112461425, 2251.4258863989576, 2191.813695835856, 2195.5169862630196, 2217.15748015753, 2220.04538048639, 2226.220822712054, 7859.842803143963, 7966.402519811038, 7987.800304992364, 8004.719834805812, 8010.198980102002, 4724.645651576111, 4850.369451971256, 4863.951360486385, 4865.464798872121, 4873.585745211662, 2867.1828528869382, 2921.576371426131, 3023.1535170152392, 3067.8312692801887, 3078.2612173078523, 978.7210644966221, 983.9803203935882, 988.6836212412031, 989.4409658542972, 1007.9092881640614, 10746.301490403177, 10953.288036277696, 10972.122230221563, 10982.835257471144, 11041.558337666484, 4341.413909122228, 4362.280223763107, 4525.298578720303, 4552.294394368878, 4635.374224479652, 9343.205827504602, 9362.0, 9370.29722770795, 9381.859272110923, 9400.811983760286, 4868.0, 4901.730404827768, 4917.286993385939, 4921.384826896638, 4922.532359425849, 3763.41667041609, 4253.532111467736, 4368.584984426474, 4408.426904502394, 4472.217361961643, 3845.8653947111716, 3864.574896761354, 3907.531096268466, 4019.397090436458, 4030.6775457963295, 1254.4856608790353, 1288.2656885575132, 1295.3199570225518, 1298.6688394459352, 1311.1608570514877, 670.8624731929942, 766.4596459496042, 813.2071230550166, 821.4373154389262, 822.7202751064547, 5282.260483532308, 5343.3587969443915, 5384.434634363413, 5449.0, 5529.253550770675, 420.9515905670826, 427.7625917615753, 434.48947486702997, 437.9299312110111, 438.7257963772642, 3585.390483617739, 3586.228960773446, 3646.343658141507, 3650.0327413235514, 3686.096906257984, 9610.087041731025, 9806.215502759762, 9949.0, 9985.20118390527, 9985.45080020606, 9755.12203901638, 9801.705948821547, 9818.116369526777, 9829.901700983042, 9831.016898310254, 6897.137857767779, 7272.927270727331, 7463.776086717407, 7488.887666685004, 7534.756765133801, 5363.490468405495, 5450.155225939986, 5481.890362192814, 5555.527780138572, 5606.0, 8994.775130621787, 9042.231410330196, 9069.455832650063, 9088.636454541744, 9161.31290153228, 9054.637814487347, 9129.269658427444, 9308.650245714198, 9370.531473426438, 9390.295727820578, 6135.294441139326, 6556.377144171296, 6638.236602790547, 6680.231773346128, 6773.93226067733, 7273.599952002691, 7301.853962920818, 7323.59720215372, 7332.450066244946, 7383.852322953403, 6566.737330506726, 6777.356151165632, 6796.626185559644, 6823.4882383822405, 6947.340002699735, 6561.442277406283, 6607.735690572324, 6671.366220209073, 6696.062551242927, 6712.160979877515, 6282.654454005074, 6340.461060809698, 6353.36466353361, 6355.777547785805, 6366.013267943476, 4394.912101758011, 4532.0, 4619.3994780678295, 4657.510961349009, 4666.8133274668635, 4542.273236282179, 4557.270836666117, 4565.3836732040745, 4613.469451013177, 4633.0039041606215], "value": 4157.865684367449, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.7691731201599, 309.57124382729916, 320.3944544810309, 320.8235470491229, 322.87730662348304, 5241.0828105081555, 5242.108841496945, 5397.568194544437, 5482.780688772449, 5494.505494505489, 5239.921401178975, 5332.546733527645, 5349.438308977559, 5358.24984502169, 5368.489993450626, 4219.30381487055, 4226.682998775089, 4233.724807887477, 4236.237477254093, 4241.897106752252, 4029.37544680575, 4216.494020717518, 4239.0, 4239.618434340909, 4241.448611680477, 4168.416421700964, 4199.286121359376, 4205.894852628693, 4207.0113523322, 4214.283571792803, 4211.220924129046, 4214.91570168598, 4220.641245494121, 4242.8727138185595, 4247.660187185049, 3741.214344987529, 3747.6627103560536, 3750.5686846012786, 3754.7559408638626, 3764.0452244987828, 5055.115354812893, 5148.922766158511, 5163.251328557376, 5215.530602245778, 5226.1638137898135, 4789.784459699322, 4843.4187897452175, 4851.199552073904, 4858.5384388483035, 4879.1461494238365, 5523.762876035212, 5686.317641883001, 5706.629069110536, 5710.343310519302, 5712.285964254468, 4049.3925911113274, 4062.979685101556, 4073.307537718595, 4073.551909289976, 4076.8369265229353, 5823.184754134425, 5946.672932988684, 5959.374265702126, 5967.7911273105665, 5981.58128930975, 4172.561881002451, 4179.868267183758, 4181.979090104591, 4186.079062606222, 4195.97902010488, 4022.5374081980362, 4258.765767882796, 4263.616274535307, 4268.466441694788, 4279.12277983019, 4819.3012013257885, 4830.420349558076, 4844.467108618049, 4847.95152048475, 4851.684640498427, 5425.647332923348, 5750.1949727038245, 5771.538276937835, 5788.88422231553, 5798.536117110621, 1429.578274409031, 1480.2154857925316, 1480.8445113263167, 1484.1021182184838, 1484.4433337498438, 986.4919566423155, 989.4607438945792, 1004.3923426327113, 1018.3889666200221, 1024.1499555369026, 4926.162552366106, 4933.519944016781, 4938.506149385104, 4943.233798761198, 4951.10880041596, 5856.970715146482, 5871.706414679249, 5872.2366092408565, 5876.882462350814, 5905.498032667186, 4809.687370320918, 4897.261472177397, 4925.950740492549, 4930.654854160174, 4935.7532123393685, 3150.5116706910007, 3161.103337997183, 3162.683731626819, 3173.8730450782173, 3181.0, 5456.0, 5479.4520547945685, 5482.314710661168, 5486.972565137228, 5494.450554944554, 5762.538996880324, 5990.430909063632, 5999.940000600025, 6015.157877897098, 6019.398060194033, 5433.130699088127, 5458.2904222450825, 5469.1249400096585, 5484.643498172606, 5514.80698175562, 5624.578156638187, 5904.557158213065, 5904.61619994699, 5916.852078698067, 5968.612040217372, 5845.386234445322, 5851.385604511635, 5853.94146058534, 5858.5898987072205, 5893.233879595625, 5544.099657987433, 5544.140658197985, 5549.417311182429, 5560.638558493685, 5576.386597474356, 3958.477923296927, 4029.3553031514816, 4098.590140985878, 4099.1801639671585, 4106.486689163855, 5626.971865140648, 5716.342620598518, 5903.793367232298, 5934.7922822701, 5938.376470470538, 5723.513501352431, 5729.427057294237, 5755.97122014404, 5758.568107391879, 5758.869218389223, 5700.401457846866, 5742.655440673406, 5753.813550850818, 5773.863670441963, 5779.508741756998, 5506.91739623898, 5561.416051314553, 5564.582656300713, 5619.606627536034, 5636.351819540805, 5733.480627633764, 5745.2818397700285, 5769.365369809234, 5773.7979170730505, 5799.188113664006, 5472.835814925558, 5577.2191893135805, 5581.795190899653, 5598.496135347838, 5617.601351182476], "value": 4286.139877949309, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.1418918581483, 725.5066554742773, 731.6890321613315, 763.2256746725651, 766.2184571736829, 11876.52493900244, 11936.462859171337, 11961.162718609701, 11996.0, 12044.096692748035, 10586.841197382046, 11761.588344407948, 11809.940950295259, 11861.822072668896, 11951.0, 769.1270408086834, 792.1009654042674, 792.9167437419075, 800.4836880212259, 800.5316889619579, 781.8358144789601, 783.4907310248356, 785.5640119733546, 788.9921100788976, 790.4545863354296, 824.8556502612049, 825.8719898415756, 826.7809030606894, 826.9421140520166, 830.2610676497922, 819.270848944434, 820.7168526858254, 821.7493664432376, 822.4530687093076, 825.1171246765963, 599.3616798109999, 600.7506884642868, 602.0878369270558, 603.6287683074877, 603.8520562462181, 13063.542776002889, 13101.934490327487, 13146.802797958038, 13153.736925261537, 13221.471141154343, 12311.507539698401, 12461.501539938483, 12599.244045357307, 12611.09944712712, 12723.23660580368, 9230.446173229628, 9288.814223715555, 9441.575129119206, 9509.952450237774, 9624.230061595055, 3292.539044533767, 3300.2574420755377, 3302.53764472974, 3314.9668503315143, 3323.302106557626, 46207.0, 46345.53654463446, 46493.30260046102, 46586.76706616479, 46692.29961550579, 9227.953860230657, 9286.67496637628, 9298.814023719622, 9472.763071538, 9521.095495927875, 17223.56941076458, 17224.224909879085, 17233.655326893644, 17234.138293085296, 17255.0, 6819.7272109116075, 6904.3095690431555, 6987.336203060803, 7048.048513450721, 7321.524100933422, 3806.08653923062, 3897.7466464679705, 3904.590018048121, 3912.1784425270444, 3949.4470774091074, 2931.7068293170514, 2981.6272965879266, 2987.9402411951637, 2996.550517422404, 3005.3538489224484, 475.7835184990821, 498.27003439960635, 501.63882004957105, 532.4595535531439, 559.4489427913502, 455.41251785197386, 521.9373675159005, 529.0239508107535, 566.3684991234826, 591.1753104419397, 6705.564138330993, 7009.894851577231, 7096.183938846995, 7235.638218089074, 7364.484486086029, 453.4603821452461, 490.279289444516, 506.9847904562869, 534.7860855657768, 575.0782964661735, 3472.461768425898, 3548.3258180945536, 3559.394902866519, 3667.3582123128344, 3808.619138086169, 50296.49703502991, 50370.0, 50476.24285635718, 50502.49497505051, 50990.0, 45291.09417811691, 45333.0, 45339.54660453419, 45512.0, 46125.76937115293, 40160.196796063916, 41262.58737412647, 41973.0, 42746.35880461796, 43376.3493547597, 6905.309469053054, 7027.9297207027275, 7120.715171393086, 7259.818504537428, 7380.852382952418, 28118.297042573282, 28634.0, 28857.56713649255, 29056.12831615055, 29373.999999999145, 34264.31471370509, 34576.82711586426, 37014.0, 37285.0, 37367.25265494729, 8852.739896600198, 8943.865842012505, 8974.41666291689, 9123.817523649357, 9202.861957070516, 9498.335116541775, 9868.210543156387, 9974.102330790263, 9982.550785214538, 9984.17264754779, 9180.311476639145, 9218.400803947721, 9228.0, 9323.673671421468, 9329.673461428818, 9729.124378805942, 9770.658026969022, 9812.26408019387, 9821.066998635119, 9904.702858914245, 9253.67612133572, 9276.443413395218, 9280.443173409618, 9393.295502837178, 9427.858582121411, 7840.725574604861, 7969.282764551218, 7988.440809143304, 8034.799130021796, 8321.583920804056, 7845.293923546908, 8136.796580085545, 8145.104038555873, 8263.547166798126, 8291.08798032204], "value": 5630.4321506224705, "unit": "score"}], "name": "dromaeo_css", "value": 6350.119335959032, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 112.0, 114.0, 113.0, 125.0, 113.0, 114.0, 113.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 137.0, 164.0, 181.0, 135.0, 155.0, 155.0, 134.0, 206.0, 136.0], "value": 156.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 165.0, 173.0, 163.0, 163.0, 162.0, 164.0, 174.0, 163.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 175.0, 76.0, 74.0, 77.0, 77.0, 155.0, 77.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 148.0, 94.0, 94.0, 94.0, 141.0, 94.0, 93.0, 143.0], "value": 108.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 147.0, 149.0, 147.0, 149.0, 146.0, 148.0, 148.0, 147.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 68.0, 65.0, 69.0, 66.0, 66.0, 79.0, 65.0, 63.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 64.0, 66.0, 65.0, 64.0, 65.0, 66.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 88.0, 80.0, 79.0, 79.0, 80.0, 81.0, 81.0, 82.0, 82.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 135.0, 143.0, 139.0, 140.0, 134.0, 136.0, 136.0, 170.0, 137.0], "value": 141.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 192.0, 180.0, 174.0, 175.0, 180.0, 180.0, 179.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 74.0, 75.0, 75.0, 73.0, 74.0, 72.0, 74.0], "value": 74.3, "unit": "score"}], "name": "kraken", "value": 1608.4999999999998, "alertThreshold": 2.0}]} 05:44:08 INFO - Return code: 0 05:44:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:44:08 ERROR - # TBPL SUCCESS # 05:44:08 INFO - Running post-action listener: _resource_record_post_action 05:44:08 INFO - Running post-run listener: _resource_record_post_run 05:44:10 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 518942720; Read time: 8; Write time: 578620 05:44:10 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234618880; Read time: 0; Write time: 328608 05:44:10 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284323840; Read time: 8; Write time: 250012 05:44:10 INFO - Running post-run listener: _upload_blobber_files 05:44:10 INFO - Blob upload gear active. 05:44:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:44:10 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 05:44:10 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:44:10 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:44:10 INFO - (blobuploader) - INFO - Open directory for files ... 05:44:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:44:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:44:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:11 INFO - (blobuploader) - INFO - Done attempting. 05:44:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:44:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:44:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:12 INFO - (blobuploader) - INFO - Done attempting. 05:44:12 INFO - (blobuploader) - INFO - Iteration through files over. 05:44:12 INFO - Return code: 0 05:44:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:44:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:44:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b09eeb6db93522151c3b46c1ffc61fc444cd0c356da41765cdcbff17e02e1909024f5ab45a39122088de1ed5cd5685371f256cc5d87ccb9b24b265936cd65124", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/042f71cab090ddabe6c3fdca80c70d3ed08eb6437e2ad858014aafa6f40e3a5872ca5eb8414083f1338cae207865dd2f1343859b3ccd5d0519c542828627892e"} 05:44:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:44:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:44:12 INFO - Contents: 05:44:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b09eeb6db93522151c3b46c1ffc61fc444cd0c356da41765cdcbff17e02e1909024f5ab45a39122088de1ed5cd5685371f256cc5d87ccb9b24b265936cd65124", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/042f71cab090ddabe6c3fdca80c70d3ed08eb6437e2ad858014aafa6f40e3a5872ca5eb8414083f1338cae207865dd2f1343859b3ccd5d0519c542828627892e"} 05:44:12 INFO - Running post-run listener: copy_logs_to_upload_dir 05:44:12 INFO - Copying logs to upload dir... 05:44:12 INFO - mkdir: /builds/slave/test/build/upload/logs 05:44:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.624550 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-04-25 05:44:11.913595) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 05:44:11.917048) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b09eeb6db93522151c3b46c1ffc61fc444cd0c356da41765cdcbff17e02e1909024f5ab45a39122088de1ed5cd5685371f256cc5d87ccb9b24b265936cd65124", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/042f71cab090ddabe6c3fdca80c70d3ed08eb6437e2ad858014aafa6f40e3a5872ca5eb8414083f1338cae207865dd2f1343859b3ccd5d0519c542828627892e"} build_url:https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/D5pYoYECQPOA_-xi3A8o-g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b09eeb6db93522151c3b46c1ffc61fc444cd0c356da41765cdcbff17e02e1909024f5ab45a39122088de1ed5cd5685371f256cc5d87ccb9b24b265936cd65124", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/042f71cab090ddabe6c3fdca80c70d3ed08eb6437e2ad858014aafa6f40e3a5872ca5eb8414083f1338cae207865dd2f1343859b3ccd5d0519c542828627892e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 05:44:11.941287) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:44:11.941583) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1461587015.656971-62615470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:44:11.991316) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:44:11.991620) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:44:11.991898) ========= ========= Total master_lag: 0.12 =========