builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1487606850.61 results: success (0) revision: d0462b0948e0b1147dcce615bddcc46379bdadb2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:30.605610) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:30.606130) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:30.606439) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:30.623504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:30.623835) ========= 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=17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-20 08:07:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.6M=0s 2017-02-20 08:07:30 (45.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178198 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:30.816707) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:30.817069) ========= 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=17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013468 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:30.868749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 08:07:30.869414) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0462b0948e0b1147dcce615bddcc46379bdadb2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev d0462b0948e0b1147dcce615bddcc46379bdadb2 --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=17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-20 08:07:30,953 truncating revision to first 12 chars 2017-02-20 08:07:30,953 Setting DEBUG logging. 2017-02-20 08:07:30,954 attempt 1/10 2017-02-20 08:07:30,954 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0462b0948e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 08:07:32,001 unpacking tar archive at: mozilla-central-d0462b0948e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.361371 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 08:07:32.247537) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:32.248290) ========= script_repo_revision: d0462b0948e0b1147dcce615bddcc46379bdadb2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:32.249214) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:32.249514) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 08:07:32.258842) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-20 08:07:32.259203) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:07:32 INFO - MultiFileLogger online at 20170220 08:07:32 in /builds/slave/test 08:07:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:07:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:07:32 INFO - {'append_to_log': False, 08:07:32 INFO - 'base_work_dir': '/builds/slave/test', 08:07:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:07:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:07:32 INFO - 'branch': 'Firefox-Non-PGO', 08:07:32 INFO - 'buildbot_json_path': 'buildprops.json', 08:07:32 INFO - 'config_files': ('talos/linux_config.py',), 08:07:32 INFO - 'default_actions': ('clobber', 08:07:32 INFO - 'read-buildbot-config', 08:07:32 INFO - 'download-and-extract', 08:07:32 INFO - 'populate-webroot', 08:07:32 INFO - 'create-virtualenv', 08:07:32 INFO - 'install', 08:07:32 INFO - 'run-tests'), 08:07:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:07:32 INFO - 'download_minidump_stackwalk': True, 08:07:32 INFO - 'download_symbols': 'ondemand', 08:07:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:07:32 INFO - 'tooltool.py': '/tools/tooltool.py', 08:07:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:07:32 INFO - '/tools/misc-python/virtualenv.py')}, 08:07:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:07:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:07:32 INFO - 'gecko_profile': False, 08:07:32 INFO - 'gecko_profile_interval': 0, 08:07:32 INFO - 'installer_path': 'installer.exe', 08:07:32 INFO - 'log_level': 'info', 08:07:32 INFO - 'log_name': 'talos', 08:07:32 INFO - 'log_to_console': True, 08:07:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:07:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:07:32 INFO - 'opt_config_files': (), 08:07:32 INFO - 'pip_index': False, 08:07:32 INFO - 'suite': 'dromaeojs', 08:07:32 INFO - 'system_bits': '32', 08:07:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:07:32 INFO - 'title': 'talos-linux64-ix-009', 08:07:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:07:32 INFO - 'use_talos_json': True, 08:07:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:07:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:07:32 INFO - 'work_dir': 'build'} 08:07:32 INFO - [mozharness: 2017-02-20 16:07:32.436975Z] Running clobber step. 08:07:32 INFO - Running pre-action listener: _resource_record_pre_action 08:07:32 INFO - Running main action method: clobber 08:07:32 INFO - rmtree: /builds/slave/test/build 08:07:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:07:33 INFO - Running post-action listener: _resource_record_post_action 08:07:33 INFO - [mozharness: 2017-02-20 16:07:33.359360Z] Finished clobber step (success) 08:07:33 INFO - [mozharness: 2017-02-20 16:07:33.359460Z] Running read-buildbot-config step. 08:07:33 INFO - Running pre-action listener: _resource_record_pre_action 08:07:33 INFO - Running main action method: read_buildbot_config 08:07:33 INFO - Using buildbot properties: 08:07:33 INFO - { 08:07:33 INFO - "project": "", 08:07:33 INFO - "product": "firefox", 08:07:33 INFO - "who": "ffxbld@noreply.mozilla.org", 08:07:33 INFO - "installer_path": "public/build/target.tar.bz2", 08:07:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:07:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:07:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:07:33 INFO - "stage_platform": "linux64", 08:07:33 INFO - "basedir": "/builds/slave/test", 08:07:33 INFO - "buildnumber": 836, 08:07:33 INFO - "platform": "ubuntu64_hw", 08:07:33 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:07:33 INFO - "slavebuilddir": "test", 08:07:33 INFO - "taskId": "WWxQyQ1pSc-QJe7LaSju4w", 08:07:33 INFO - "branch": "mozilla-central", 08:07:33 INFO - "script_repo_revision": "production", 08:07:33 INFO - "revision": "d0462b0948e0b1147dcce615bddcc46379bdadb2", 08:07:33 INFO - "slavename": "talos-linux64-ix-009", 08:07:33 INFO - "repo_path": "mozilla-central" 08:07:33 INFO - } 08:07:33 INFO - Finding installer, test and symbols from parent task. 08:07:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WWxQyQ1pSc-QJe7LaSju4w'}, attempt #1 08:07:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WWxQyQ1pSc-QJe7LaSju4w'}, attempt #1 08:07:34 INFO - Task dependencies: A5aj3my4QS-xYc-RUvXGMw 08:07:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw'}, attempt #1 08:07:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.tar.bz2 08:07:34 INFO - Set test_url: https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/test_packages.json 08:07:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.crashreporter-symbols.zip 08:07:34 INFO - Running post-action listener: _resource_record_post_action 08:07:34 INFO - [mozharness: 2017-02-20 16:07:34.762792Z] Finished read-buildbot-config step (success) 08:07:34 INFO - [mozharness: 2017-02-20 16:07:34.763007Z] Running download-and-extract step. 08:07:34 INFO - Running pre-action listener: _resource_record_pre_action 08:07:34 INFO - Running main action method: download_and_extract 08:07:34 INFO - mkdir: /builds/slave/test/build/tests 08:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:34 INFO - trying https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.test_packages.json 08:07:34 INFO - Downloading https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:07:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:07:35 INFO - Downloaded 896 bytes. 08:07:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:07:35 INFO - Using the following test package requirements: 08:07:35 INFO - {u'common': [u'target.common.tests.zip'], 08:07:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:07:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:07:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:07:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:07:35 INFO - u'mozbase': [u'target.common.tests.zip'], 08:07:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:07:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:07:35 INFO - u'web-platform': [u'target.common.tests.zip', 08:07:35 INFO - u'target.web-platform.tests.zip'], 08:07:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:07:35 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:07:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.common.tests.zip 08:07:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:07:35 INFO - Fetch https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.common.tests.zip into memory 08:07:36 INFO - Expected file size: 57165377 08:07:36 INFO - Obtained file size: 57165377 08:07:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:07:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.common.tests.zip 08:07:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:07:46 INFO - Fetch https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.common.tests.zip into memory 08:07:47 INFO - Expected file size: 57165377 08:07:47 INFO - Obtained file size: 57165377 08:07:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.talos.tests.zip 08:07:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:07:57 INFO - Fetch https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.talos.tests.zip into memory 08:07:58 INFO - Expected file size: 14404319 08:07:58 INFO - Obtained file size: 14404319 08:07:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:07:58 INFO - trying https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.tar.bz2 08:07:58 INFO - Downloading https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:07:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:08:01 INFO - Downloaded 61544700 bytes. 08:08:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.tar.bz2 08:08:01 INFO - mkdir: /builds/slave/test/properties 08:08:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:08:01 INFO - Writing to file /builds/slave/test/properties/build_url 08:08:01 INFO - Contents: 08:08:01 INFO - build_url:https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.tar.bz2 08:08:01 INFO - Running post-action listener: _resource_record_post_action 08:08:01 INFO - Running post-action listener: set_extra_try_arguments 08:08:01 INFO - [mozharness: 2017-02-20 16:08:01.404762Z] Finished download-and-extract step (success) 08:08:01 INFO - [mozharness: 2017-02-20 16:08:01.404986Z] Running populate-webroot step. 08:08:01 INFO - Running pre-action listener: _resource_record_pre_action 08:08:01 INFO - Running main action method: populate_webroot 08:08:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:08:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:08:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:08:01 INFO - '--tppagecycles', 08:08:01 INFO - '7'], 08:08:01 INFO - 'tests': ['tsvgm']}}, 08:08:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:08:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:08:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:08:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:08:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:08:01 INFO - 'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:08:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:08:01 INFO - 'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['damp', 'tps']}, 08:08:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:08:01 INFO - 'tests': ['damp', 'tps']}, 08:08:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['dromaeo_dom']}, 08:08:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:08:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['basic_compositor_video']}, 08:08:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:08:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['a11yr', 08:08:01 INFO - 'ts_paint', 08:08:01 INFO - 'tpaint', 08:08:01 INFO - 'sessionrestore', 08:08:01 INFO - 'sessionrestore_no_auto_restore', 08:08:01 INFO - 'tabpaint']}, 08:08:01 INFO - 'other-e10s': {'tests': ['a11yr', 08:08:01 INFO - 'ts_paint', 08:08:01 INFO - 'tpaint', 08:08:01 INFO - 'sessionrestore', 08:08:01 INFO - 'sessionrestore_no_auto_restore', 08:08:01 INFO - 'tabpaint']}, 08:08:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:08:01 INFO - 'ts_paint', 08:08:01 INFO - 'tpaint', 08:08:01 INFO - 'sessionrestore', 08:08:01 INFO - 'sessionrestore_no_auto_restore', 08:08:01 INFO - 'tabpaint']}, 08:08:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:08:01 INFO - 'ts_paint', 08:08:01 INFO - 'tpaint', 08:08:01 INFO - 'sessionrestore', 08:08:01 INFO - 'sessionrestore_no_auto_restore', 08:08:01 INFO - 'tabpaint']}, 08:08:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['a11yr', 08:08:01 INFO - 'ts_paint', 08:08:01 INFO - 'tpaint', 08:08:01 INFO - 'sessionrestore', 08:08:01 INFO - 'sessionrestore_no_auto_restore', 08:08:01 INFO - 'tabpaint']}, 08:08:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['a11yr', 08:08:01 INFO - 'ts_paint', 08:08:01 INFO - 'tpaint', 08:08:01 INFO - 'sessionrestore', 08:08:01 INFO - 'sessionrestore_no_auto_restore', 08:08:01 INFO - 'tabpaint']}, 08:08:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['tsvgx', 08:08:01 INFO - 'tsvgr_opacity', 08:08:01 INFO - 'tart', 08:08:01 INFO - 'tscrollx', 08:08:01 INFO - 'cart', 08:08:01 INFO - 'tsvg_static']}, 08:08:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:08:01 INFO - 'tsvgr_opacity', 08:08:01 INFO - 'tart', 08:08:01 INFO - 'tscrollx', 08:08:01 INFO - 'cart', 08:08:01 INFO - 'tsvg_static']}, 08:08:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:08:01 INFO - 'talos_options': ['--disable-e10s'], 08:08:01 INFO - 'tests': ['tp5o']}, 08:08:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:08:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:08:01 INFO - 'talos_options': ['--disable-e10s', 08:08:01 INFO - '--xperf_path', 08:08:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:01 INFO - 'tests': ['tp5n']}, 08:08:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:08:01 INFO - 'talos_options': ['--xperf_path', 08:08:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:01 INFO - 'tests': ['tp5n']}}, 08:08:01 INFO - 'talos.zip': {'path': '', 08:08:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:08:01 INFO - Running post-action listener: _resource_record_post_action 08:08:01 INFO - [mozharness: 2017-02-20 16:08:01.423380Z] Finished populate-webroot step (success) 08:08:01 INFO - [mozharness: 2017-02-20 16:08:01.423470Z] Running create-virtualenv step. 08:08:01 INFO - Running pre-action listener: _resource_record_pre_action 08:08:01 INFO - Running main action method: create_virtualenv 08:08:01 INFO - Creating virtualenv /builds/slave/test/build/venv 08:08:01 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 08:08:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:08:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:08:01 INFO - Using real prefix '/usr' 08:08:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:08:02 INFO - Installing distribute.............................................................................................................................................................................................done. 08:08:04 INFO - Installing pip.................done. 08:08:04 INFO - Return code: 0 08:08:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:08:04 INFO - Reading from file tmpfile_stdout 08:08:04 INFO - Output received: 08:08:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:08:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:04 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 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:04 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 08:08:04 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 08:08:04 INFO - Using env: {'DISPLAY': ':0', 08:08:04 INFO - 'HOME': '/home/cltbld', 08:08:04 INFO - 'LANG': 'en_US.UTF-8', 08:08:04 INFO - 'LANGUAGE': 'en_US:en', 08:08:04 INFO - 'LOGNAME': 'cltbld', 08:08:04 INFO - 'MAIL': '/var/mail/cltbld', 08:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:04 INFO - 'MOZ_NO_REMOTE': '1', 08:08:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:04 INFO - 'NO_EM_RESTART': '1', 08:08:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:04 INFO - 'PWD': '/builds/slave/test', 08:08:04 INFO - 'SHELL': '/bin/bash', 08:08:04 INFO - 'SHLVL': '1', 08:08:04 INFO - 'TERM': 'linux', 08:08:04 INFO - 'TMOUT': '86400', 08:08:04 INFO - 'USER': 'cltbld', 08:08:04 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:04 INFO - '_': '/tools/buildbot/bin/python'} 08:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:04 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 08:08:04 INFO - Cleaning up... 08:08:04 INFO - Return code: 0 08:08:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:08:04 INFO - Using env: {'DISPLAY': ':0', 08:08:04 INFO - 'HOME': '/home/cltbld', 08:08:04 INFO - 'LANG': 'en_US.UTF-8', 08:08:04 INFO - 'LANGUAGE': 'en_US:en', 08:08:04 INFO - 'LOGNAME': 'cltbld', 08:08:04 INFO - 'MAIL': '/var/mail/cltbld', 08:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:04 INFO - 'MOZ_NO_REMOTE': '1', 08:08:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:04 INFO - 'NO_EM_RESTART': '1', 08:08:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:04 INFO - 'PWD': '/builds/slave/test', 08:08:04 INFO - 'SHELL': '/bin/bash', 08:08:04 INFO - 'SHLVL': '1', 08:08:04 INFO - 'TERM': 'linux', 08:08:04 INFO - 'TMOUT': '86400', 08:08:04 INFO - 'USER': 'cltbld', 08:08:04 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:04 INFO - '_': '/tools/buildbot/bin/python'} 08:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:04 INFO - Downloading/unpacking psutil>=3.1.1 08:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:08:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:06 INFO - Installing collected packages: psutil 08:08:06 INFO - Running setup.py install for psutil 08:08:06 INFO - building 'psutil._psutil_linux' extension 08:08:06 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 08:08:07 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 08:08:07 INFO - building 'psutil._psutil_posix' extension 08:08:07 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 08:08:07 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 08:08:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:07 INFO - Successfully installed psutil 08:08:07 INFO - Cleaning up... 08:08:07 INFO - Return code: 0 08:08:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:08:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:08:07 INFO - Using env: {'DISPLAY': ':0', 08:08:07 INFO - 'HOME': '/home/cltbld', 08:08:07 INFO - 'LANG': 'en_US.UTF-8', 08:08:07 INFO - 'LANGUAGE': 'en_US:en', 08:08:07 INFO - 'LOGNAME': 'cltbld', 08:08:07 INFO - 'MAIL': '/var/mail/cltbld', 08:08:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:07 INFO - 'MOZ_NO_REMOTE': '1', 08:08:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:07 INFO - 'NO_EM_RESTART': '1', 08:08:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:07 INFO - 'PWD': '/builds/slave/test', 08:08:07 INFO - 'SHELL': '/bin/bash', 08:08:07 INFO - 'SHLVL': '1', 08:08:07 INFO - 'TERM': 'linux', 08:08:07 INFO - 'TMOUT': '86400', 08:08:07 INFO - 'USER': 'cltbld', 08:08:07 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:07 INFO - '_': '/tools/buildbot/bin/python'} 08:08:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:08:07 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 08:08:07 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 08:08:07 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 08:08:07 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 08:08:07 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 08:08:07 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 08:08:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:08:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:08:09 INFO - Installing collected packages: mozsystemmonitor 08:08:09 INFO - Running setup.py install for mozsystemmonitor 08:08:09 INFO - Successfully installed mozsystemmonitor 08:08:09 INFO - Cleaning up... 08:08:09 INFO - Return code: 0 08:08:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:08:09 INFO - Using env: {'DISPLAY': ':0', 08:08:09 INFO - 'HOME': '/home/cltbld', 08:08:09 INFO - 'LANG': 'en_US.UTF-8', 08:08:09 INFO - 'LANGUAGE': 'en_US:en', 08:08:09 INFO - 'LOGNAME': 'cltbld', 08:08:09 INFO - 'MAIL': '/var/mail/cltbld', 08:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:09 INFO - 'MOZ_NO_REMOTE': '1', 08:08:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:09 INFO - 'NO_EM_RESTART': '1', 08:08:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:09 INFO - 'PWD': '/builds/slave/test', 08:08:09 INFO - 'SHELL': '/bin/bash', 08:08:09 INFO - 'SHLVL': '1', 08:08:09 INFO - 'TERM': 'linux', 08:08:09 INFO - 'TMOUT': '86400', 08:08:09 INFO - 'USER': 'cltbld', 08:08:09 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:09 INFO - '_': '/tools/buildbot/bin/python'} 08:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:09 INFO - Downloading/unpacking jsonschema==2.5.1 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:08:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:12 INFO - Downloading functools32-3.2.3-2.tar.gz 08:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:08:12 INFO - warning: no files found matching '*.txt' 08:08:12 INFO - no previously-included directories found matching 'build' 08:08:12 INFO - no previously-included directories found matching 'dist' 08:08:12 INFO - no previously-included directories found matching '.git*' 08:08:12 INFO - Installing collected packages: jsonschema, functools32 08:08:12 INFO - Running setup.py install for functools32 08:08:12 INFO - warning: no files found matching '*.txt' 08:08:12 INFO - no previously-included directories found matching 'build' 08:08:12 INFO - no previously-included directories found matching 'dist' 08:08:12 INFO - no previously-included directories found matching '.git*' 08:08:12 INFO - Successfully installed jsonschema functools32 08:08:12 INFO - Cleaning up... 08:08:12 INFO - Return code: 0 08:08:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:08:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:12 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:12 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:08:12 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 functools32==3.2.3-2 08:08:12 INFO - Using env: {'DISPLAY': ':0', 08:08:12 INFO - 'HOME': '/home/cltbld', 08:08:12 INFO - 'LANG': 'en_US.UTF-8', 08:08:12 INFO - 'LANGUAGE': 'en_US:en', 08:08:12 INFO - 'LOGNAME': 'cltbld', 08:08:12 INFO - 'MAIL': '/var/mail/cltbld', 08:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:12 INFO - 'MOZ_NO_REMOTE': '1', 08:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:12 INFO - 'NO_EM_RESTART': '1', 08:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:12 INFO - 'PWD': '/builds/slave/test', 08:08:12 INFO - 'SHELL': '/bin/bash', 08:08:12 INFO - 'SHLVL': '1', 08:08:12 INFO - 'TERM': 'linux', 08:08:12 INFO - 'TMOUT': '86400', 08:08:12 INFO - 'USER': 'cltbld', 08:08:12 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:12 INFO - '_': '/tools/buildbot/bin/python'} 08:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:08:12 INFO - Cleaning up... 08:08:12 INFO - Return code: 0 08:08:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:08:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:12 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 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:12 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 08:08:12 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 08:08:12 INFO - Using env: {'DISPLAY': ':0', 08:08:12 INFO - 'HOME': '/home/cltbld', 08:08:12 INFO - 'LANG': 'en_US.UTF-8', 08:08:12 INFO - 'LANGUAGE': 'en_US:en', 08:08:12 INFO - 'LOGNAME': 'cltbld', 08:08:12 INFO - 'MAIL': '/var/mail/cltbld', 08:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:12 INFO - 'MOZ_NO_REMOTE': '1', 08:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:12 INFO - 'NO_EM_RESTART': '1', 08:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:12 INFO - 'PWD': '/builds/slave/test', 08:08:12 INFO - 'SHELL': '/bin/bash', 08:08:12 INFO - 'SHLVL': '1', 08:08:12 INFO - 'TERM': 'linux', 08:08:12 INFO - 'TMOUT': '86400', 08:08:12 INFO - 'USER': 'cltbld', 08:08:12 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:12 INFO - '_': '/tools/buildbot/bin/python'} 08:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:12 INFO - Downloading/unpacking blobuploader==1.2.4 08:08:12 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 08:08:12 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 08:08:12 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 08:08:12 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 08:08:12 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 08:08:12 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 08:08:14 INFO - Downloading blobuploader-1.2.4.tar.gz 08:08:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:08:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:08:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:15 INFO - Downloading docopt-0.6.1.tar.gz 08:08:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:08:15 INFO - Installing collected packages: blobuploader, requests, docopt 08:08:15 INFO - Running setup.py install for blobuploader 08:08:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:08:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:08:15 INFO - Running setup.py install for requests 08:08:16 INFO - Running setup.py install for docopt 08:08:16 INFO - Successfully installed blobuploader requests docopt 08:08:16 INFO - Cleaning up... 08:08:16 INFO - Return code: 0 08:08:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:16 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:16 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 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:16 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 08:08:16 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 08:08:16 INFO - Using env: {'DISPLAY': ':0', 08:08:16 INFO - 'HOME': '/home/cltbld', 08:08:16 INFO - 'LANG': 'en_US.UTF-8', 08:08:16 INFO - 'LANGUAGE': 'en_US:en', 08:08:16 INFO - 'LOGNAME': 'cltbld', 08:08:16 INFO - 'MAIL': '/var/mail/cltbld', 08:08:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:16 INFO - 'MOZ_NO_REMOTE': '1', 08:08:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:16 INFO - 'NO_EM_RESTART': '1', 08:08:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:16 INFO - 'PWD': '/builds/slave/test', 08:08:16 INFO - 'SHELL': '/bin/bash', 08:08:16 INFO - 'SHLVL': '1', 08:08:16 INFO - 'TERM': 'linux', 08:08:16 INFO - 'TMOUT': '86400', 08:08:16 INFO - 'USER': 'cltbld', 08:08:16 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:16 INFO - '_': '/tools/buildbot/bin/python'} 08:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:08:16 INFO - Running setup.py (path:/tmp/pip-tEc9EX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:08:16 INFO - Running setup.py (path:/tmp/pip-hRDRdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:08:16 INFO - Running setup.py (path:/tmp/pip-JcWMQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:08:16 INFO - Running setup.py (path:/tmp/pip-w50YZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:08:16 INFO - Running setup.py (path:/tmp/pip-gPmg6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:08:16 INFO - Running setup.py (path:/tmp/pip-fX12Kj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:08:17 INFO - Running setup.py (path:/tmp/pip-7EvBjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:08:17 INFO - Running setup.py (path:/tmp/pip-W0noKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:08:17 INFO - Running setup.py (path:/tmp/pip-cFq1Ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:08:17 INFO - Running setup.py (path:/tmp/pip-bHla2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:08:17 INFO - Running setup.py (path:/tmp/pip-BBy1HX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:08:17 INFO - Running setup.py (path:/tmp/pip-rU9T1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:08:17 INFO - Running setup.py (path:/tmp/pip-M5D3bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:08:17 INFO - Running setup.py (path:/tmp/pip-OqHvPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:17 INFO - Running setup.py (path:/tmp/pip-rLpKDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:08:17 INFO - Running setup.py (path:/tmp/pip-zfKVsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:08:17 INFO - Running setup.py (path:/tmp/pip-8invxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:08:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:08:17 INFO - Running setup.py install for manifestparser 08:08:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:08:17 INFO - Running setup.py install for mozcrash 08:08:17 INFO - Running setup.py install for mozdebug 08:08:18 INFO - Running setup.py install for mozdevice 08:08:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Running setup.py install for mozfile 08:08:18 INFO - Running setup.py install for mozhttpd 08:08:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Running setup.py install for mozinfo 08:08:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Running setup.py install for mozInstall 08:08:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Running setup.py install for mozleak 08:08:18 INFO - Running setup.py install for mozlog 08:08:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Running setup.py install for moznetwork 08:08:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:08:18 INFO - Running setup.py install for mozprocess 08:08:19 INFO - Running setup.py install for mozprofile 08:08:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:08:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:08:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:08:19 INFO - Running setup.py install for mozrunner 08:08:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:08:19 INFO - Running setup.py install for mozscreenshot 08:08:19 INFO - Running setup.py install for moztest 08:08:19 INFO - Running setup.py install for mozversion 08:08:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:08:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:08:19 INFO - Cleaning up... 08:08:19 INFO - Return code: 0 08:08:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:19 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:19 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 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:19 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 08:08:19 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 08:08:19 INFO - Using env: {'DISPLAY': ':0', 08:08:19 INFO - 'HOME': '/home/cltbld', 08:08:19 INFO - 'LANG': 'en_US.UTF-8', 08:08:19 INFO - 'LANGUAGE': 'en_US:en', 08:08:19 INFO - 'LOGNAME': 'cltbld', 08:08:19 INFO - 'MAIL': '/var/mail/cltbld', 08:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:19 INFO - 'MOZ_NO_REMOTE': '1', 08:08:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:19 INFO - 'NO_EM_RESTART': '1', 08:08:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:19 INFO - 'PWD': '/builds/slave/test', 08:08:19 INFO - 'SHELL': '/bin/bash', 08:08:19 INFO - 'SHLVL': '1', 08:08:19 INFO - 'TERM': 'linux', 08:08:19 INFO - 'TMOUT': '86400', 08:08:19 INFO - 'USER': 'cltbld', 08:08:19 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:19 INFO - '_': '/tools/buildbot/bin/python'} 08:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:08:19 INFO - Running setup.py (path:/tmp/pip-epWbIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:08:19 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)) 08:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:08:19 INFO - Running setup.py (path:/tmp/pip-Qq2QSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:08:19 INFO - Running setup.py (path:/tmp/pip-uK09_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:08:19 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:08:20 INFO - Running setup.py (path:/tmp/pip-BXwnYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:08:20 INFO - Running setup.py (path:/tmp/pip-7m6n93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:08:20 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:08:20 INFO - Running setup.py (path:/tmp/pip-p9lqMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:08:20 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:08:20 INFO - Running setup.py (path:/tmp/pip-svFNxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:08:20 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:08:20 INFO - Running setup.py (path:/tmp/pip-Gxf99F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:08:20 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:08:20 INFO - Running setup.py (path:/tmp/pip-5NzZwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:08:20 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:08:20 INFO - Running setup.py (path:/tmp/pip-fTUHQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:08:20 INFO - Running setup.py (path:/tmp/pip-wfGeWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:08:20 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:08:20 INFO - Running setup.py (path:/tmp/pip-wsrw4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:08:20 INFO - Running setup.py (path:/tmp/pip-Zrkduo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:08:20 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:08:20 INFO - Running setup.py (path:/tmp/pip-vJV3Cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:20 INFO - Running setup.py (path:/tmp/pip-_1HElS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:08:20 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:08:20 INFO - Running setup.py (path:/tmp/pip-sdmJs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:08:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:08:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:08:20 INFO - Running setup.py (path:/tmp/pip-FLruDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:08:21 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)) 08:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:08:21 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)) 08:08:21 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:08:21 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:08:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:23 INFO - Downloading blessings-1.6.tar.gz 08:08:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:08:23 INFO - Installing collected packages: blessings 08:08:23 INFO - Running setup.py install for blessings 08:08:23 INFO - Successfully installed blessings 08:08:23 INFO - Cleaning up... 08:08:23 INFO - Return code: 0 08:08:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:08:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:08:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:08:23 INFO - Reading from file tmpfile_stdout 08:08:23 INFO - Current package versions: 08:08:23 INFO - argparse == 1.2.1 08:08:23 INFO - blessings == 1.6 08:08:23 INFO - blobuploader == 1.2.4 08:08:23 INFO - docopt == 0.6.1 08:08:23 INFO - functools32 == 3.2.3-2 08:08:23 INFO - jsonschema == 2.5.1 08:08:23 INFO - manifestparser == 1.1 08:08:23 INFO - mozInstall == 1.12 08:08:23 INFO - mozcrash == 1.0 08:08:23 INFO - mozdebug == 0.1 08:08:23 INFO - mozdevice == 0.49 08:08:23 INFO - mozfile == 1.2 08:08:23 INFO - mozhttpd == 0.7 08:08:23 INFO - mozinfo == 0.9 08:08:23 INFO - mozleak == 0.1 08:08:23 INFO - mozlog == 3.4 08:08:23 INFO - moznetwork == 0.27 08:08:23 INFO - mozprocess == 0.25 08:08:23 INFO - mozprofile == 0.28 08:08:23 INFO - mozrunner == 6.13 08:08:23 INFO - mozscreenshot == 0.1 08:08:23 INFO - mozsystemmonitor == 0.3 08:08:23 INFO - moztest == 0.8 08:08:23 INFO - mozversion == 1.4 08:08:23 INFO - psutil == 3.1.1 08:08:23 INFO - requests == 1.2.3 08:08:23 INFO - wsgiref == 0.1.2 08:08:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:23 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 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:23 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 08:08:23 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 08:08:23 INFO - Using env: {'DISPLAY': ':0', 08:08:23 INFO - 'HOME': '/home/cltbld', 08:08:23 INFO - 'LANG': 'en_US.UTF-8', 08:08:23 INFO - 'LANGUAGE': 'en_US:en', 08:08:23 INFO - 'LOGNAME': 'cltbld', 08:08:23 INFO - 'MAIL': '/var/mail/cltbld', 08:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:23 INFO - 'MOZ_NO_REMOTE': '1', 08:08:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:23 INFO - 'NO_EM_RESTART': '1', 08:08:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:23 INFO - 'PWD': '/builds/slave/test', 08:08:23 INFO - 'SHELL': '/bin/bash', 08:08:23 INFO - 'SHLVL': '1', 08:08:23 INFO - 'TERM': 'linux', 08:08:23 INFO - 'TMOUT': '86400', 08:08:23 INFO - 'USER': 'cltbld', 08:08:23 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:23 INFO - '_': '/tools/buildbot/bin/python'} 08:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 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)) 08:08:23 INFO - Cleaning up... 08:08:23 INFO - Return code: 0 08:08:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19c8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, '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': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', '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 08:08:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:08:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:08:23 INFO - Using env: {'DISPLAY': ':0', 08:08:23 INFO - 'HOME': '/home/cltbld', 08:08:23 INFO - 'LANG': 'en_US.UTF-8', 08:08:23 INFO - 'LANGUAGE': 'en_US:en', 08:08:23 INFO - 'LOGNAME': 'cltbld', 08:08:23 INFO - 'MAIL': '/var/mail/cltbld', 08:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:23 INFO - 'MOZ_NO_REMOTE': '1', 08:08:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:23 INFO - 'NO_EM_RESTART': '1', 08:08:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:23 INFO - 'PWD': '/builds/slave/test', 08:08:23 INFO - 'SHELL': '/bin/bash', 08:08:23 INFO - 'SHLVL': '1', 08:08:23 INFO - 'TERM': 'linux', 08:08:23 INFO - 'TMOUT': '86400', 08:08:23 INFO - 'USER': 'cltbld', 08:08:23 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:23 INFO - '_': '/tools/buildbot/bin/python'} 08:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:08:23 INFO - Cleaning up... 08:08:23 INFO - Return code: 0 08:08:23 INFO - Running post-action listener: _resource_record_post_action 08:08:23 INFO - Running post-action listener: _start_resource_monitoring 08:08:23 INFO - Starting resource monitoring. 08:08:24 INFO - [mozharness: 2017-02-20 16:08:24.000353Z] Finished create-virtualenv step (success) 08:08:24 INFO - [mozharness: 2017-02-20 16:08:24.000866Z] Running install step. 08:08:24 INFO - Running pre-action listener: _resource_record_pre_action 08:08:24 INFO - Running main action method: install 08:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:08:24 INFO - Reading from file tmpfile_stdout 08:08:24 INFO - Detecting whether we're running mozinstall >=1.0... 08:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:08:24 INFO - Reading from file tmpfile_stdout 08:08:24 INFO - Output received: 08:08:24 INFO - Usage: mozinstall [options] installer 08:08:24 INFO - Options: 08:08:24 INFO - -h, --help show this help message and exit 08:08:24 INFO - -d DEST, --destination=DEST 08:08:24 INFO - Directory to install application into. [default: 08:08:24 INFO - "/builds/slave/test"] 08:08:24 INFO - --app=APP Application being installed. [default: firefox] 08:08:24 INFO - mkdir: /builds/slave/test/build/application 08:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:08:39 INFO - Reading from file tmpfile_stdout 08:08:39 INFO - Output received: 08:08:39 INFO - /builds/slave/test/build/application/firefox/firefox 08:08:39 INFO - Running post-action listener: _resource_record_post_action 08:08:39 INFO - [mozharness: 2017-02-20 16:08:39.801345Z] Finished install step (success) 08:08:39 INFO - [mozharness: 2017-02-20 16:08:39.801679Z] Running run-tests step. 08:08:39 INFO - Running pre-action listener: _resource_record_pre_action 08:08:39 INFO - Running main action method: run_tests 08:08:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:08:39 INFO - Python 2.7.3 08:08:39 INFO - Return code: 0 08:08:39 INFO - grabbing minidump binary from tooltool 08:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:39 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6b290>, '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 0x1b6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6c040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:08:39 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 08:08:39 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 08:08:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:08:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:08:39 INFO - Return code: 0 08:08:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:08:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:08:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:08:39 INFO - ENV: RUST_BACKTRACE is now 1 08:08:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:08:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:08:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:08:39 INFO - Using env: {'DISPLAY': ':0', 08:08:39 INFO - 'HOME': '/home/cltbld', 08:08:39 INFO - 'LANG': 'en_US.UTF-8', 08:08:39 INFO - 'LANGUAGE': 'en_US:en', 08:08:39 INFO - 'LOGNAME': 'cltbld', 08:08:39 INFO - 'MAIL': '/var/mail/cltbld', 08:08:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:08:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:39 INFO - 'MOZ_NO_REMOTE': '1', 08:08:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:08:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:08:39 INFO - 'NO_EM_RESTART': '1', 08:08:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:39 INFO - 'PWD': '/builds/slave/test', 08:08:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:08:39 INFO - 'RUST_BACKTRACE': '1', 08:08:39 INFO - 'SHELL': '/bin/bash', 08:08:39 INFO - 'SHLVL': '1', 08:08:39 INFO - 'TERM': 'linux', 08:08:39 INFO - 'TMOUT': '86400', 08:08:39 INFO - 'USER': 'cltbld', 08:08:39 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329', 08:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:39 INFO - '_': '/tools/buildbot/bin/python'} 08:08:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:08:40 INFO - mozversion application_buildid: 20170220154045 08:08:40 INFO - mozversion application_changeset: d0462b0948e0b1147dcce615bddcc46379bdadb2 08:08:40 INFO - mozversion application_display_name: Nightly 08:08:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:08:40 INFO - mozversion application_name: Firefox 08:08:40 INFO - mozversion application_remotingname: firefox 08:08:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:08:40 INFO - mozversion application_vendor: Mozilla 08:08:40 INFO - mozversion application_version: 54.0a1 08:08:40 INFO - mozversion platform_buildid: 20170220154045 08:08:40 INFO - mozversion platform_changeset: d0462b0948e0b1147dcce615bddcc46379bdadb2 08:08:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:08:40 INFO - mozversion platform_version: 54.0a1 08:08:40 INFO - using testdate: 1487606920 08:08:40 INFO - actual date: 1487606920 08:08:40 INFO - starting webserver on 'localhost:33148' 08:08:40 INFO - SUITE-START | Running 2 tests 08:08:40 INFO - TEST-START | dromaeo_css 08:08:40 INFO - operating with platform_type : linux_ 08:08:40 INFO - Initialising browser for dromaeo_css test... 08:08:40 INFO - TEST-INFO | started process 15031 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptX0wKv/profile http://localhost:33148/getInfo.html) 08:08:43 INFO - PROCESS | 15031 | Iterating over profile /tmp/tmptX0wKv/profile 08:08:44 INFO - PROCESS | 15031 | __metrics Screen width/height:1600/1200 08:08:44 INFO - PROCESS | 15031 | colorDepth:24 08:08:44 INFO - PROCESS | 15031 | Browser inner width/height: 1024/697 08:08:44 INFO - PROCESS | 15031 | __metrics 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/frequencyCap.json = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/addons.json = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/storage.sqlite = Mon Feb 20 2017 08:08:44 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/safebrowsing = Mon Feb 20 2017 08:08:44 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/.parentlock = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/extensions.json = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/cookies.sqlite = Mon Feb 20 2017 08:08:43 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/sessionCheckpoints.json = Mon Feb 20 2017 08:08:44 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | Stat failure 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/startupCache = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/safebrowsing-backup = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/minidumps = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/crashes = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/bookmarkbackups = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/key3.db = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/extensions = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/content-prefs.sqlite = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/secmod.db = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/webappsstore.sqlite = Mon Feb 20 2017 08:08:44 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/user.js = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/places.sqlite-shm = Mon Feb 20 2017 08:08:41 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/thumbnails = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/prefs.js = Mon Feb 20 2017 08:07:58 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/directoryLinks.json = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/extensions.ini = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/places.sqlite-wal = Mon Feb 20 2017 08:08:44 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/compatibility.ini = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/localstore.rdf = Mon Feb 20 2017 08:07:58 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/gmp = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | Stat failure 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/cache2 = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/permissions.sqlite = Mon Feb 20 2017 08:08:41 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/mimeTypes.rdf = Mon Feb 20 2017 08:08:44 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/pluginreg.dat = Mon Feb 20 2017 08:08:42 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/places.sqlite = Mon Feb 20 2017 08:08:44 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/blocklist.xml = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:44 INFO - PROCESS | 15031 | No birth date. Using mtime. 08:08:44 INFO - PROCESS | 15031 | Using date: /tmp/tmptX0wKv/profile/cert8.db = Mon Feb 20 2017 08:08:40 GMT-0800 (PST) 08:08:45 INFO - PROCESS | 15031 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:08:45 INFO - PROCESS | 15031 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:08:46 INFO - TEST-INFO | 15031: exit 0 08:08:46 INFO - Browser initialized. 08:08:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:08:46 INFO - TEST-INFO | started process 15120 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptX0wKv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:59 INFO - PROCESS | 15120 | RSS: Main: 146558976 08:08:59 INFO - PROCESS | 15120 | 08:12:05 INFO - PROCESS | 15120 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33148/tests/dromaeo/cssquery-ext.html) 08:12:06 INFO - PROCESS | 15120 | RSS: Main: 273043456 08:12:06 INFO - PROCESS | 15120 | 08:13:48 INFO - PROCESS | 15120 | ************************* 08:13:48 INFO - PROCESS | 15120 | A coding exception was thrown and uncaught in a Task. 08:13:48 INFO - PROCESS | 15120 | 08:13:48 ERROR - PROCESS | 15120 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:13:48 INFO - PROCESS | 15120 | Full stack: 08:13:48 INFO - PROCESS | 15120 | ************************* 08:15:09 INFO - PROCESS | 15120 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-ext.html (next: http://localhost:33148/tests/dromaeo/cssquery-jquery.html) 08:15:10 INFO - PROCESS | 15120 | RSS: Main: 242655232 08:15:10 INFO - PROCESS | 15120 | 08:18:12 INFO - PROCESS | 15120 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33148/tests/dromaeo/cssquery-mootools.html) 08:18:13 INFO - PROCESS | 15120 | RSS: Main: 183894016 08:18:13 INFO - PROCESS | 15120 | 08:21:15 INFO - PROCESS | 15120 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33148/tests/dromaeo/cssquery-prototype.html) 08:21:16 INFO - PROCESS | 15120 | RSS: Main: 183529472 08:21:16 INFO - PROCESS | 15120 | 08:24:19 INFO - PROCESS | 15120 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33148/tests/dromaeo/cssquery-yui.html) 08:24:19 INFO - PROCESS | 15120 | RSS: Main: 190341120 08:24:19 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | Cycle 1(1): loaded http://localhost:33148/tests/dromaeo/cssquery-yui.html (next: http://localhost:33148/tests/dromaeo/cssquery-dojo.html) 08:27:23 INFO - PROCESS | 15120 | RSS: Main: 268828672 08:27:23 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | __start_tp_report 08:27:23 INFO - PROCESS | 15120 | _x_x_mozilla_page_load 08:27:23 INFO - PROCESS | 15120 | _x_x_mozilla_page_load_details 08:27:23 INFO - PROCESS | 15120 | |i|pagename|runs| 08:27:23 INFO - PROCESS | 15120 | |0;dojo.html;2555;2573.871306434678;2595.558755011648;2597.454534547745;2611.5429799785034;4496.258117410632;4547.113312903985;4724.149653062447;4762.595179409755;4774.904501909959;4698.177818881699;4711.528847115295;4750.121227572901;4756.857294281169;4827.034593081389;4322.762248076347;4331.978340108295;4368.650507959355;4409.933850992237;4426.44669416323;4407.933880991788;4422.248217802965;4454.665900057508;4469.418975533193;4487.214737420938;4277.550857159985;4314.676399270067;4318.913621727579;4359.564043595647;4374.081442896995;4281.036766727492;4288.421063156465;4354.847580334673;4370.147821174872;4381.364702118175;4266.935995960063;4274.273373526508;4312.072904325553;4335.544767799367;4346.174226896879;4588.7935042923145;4649.465311489154;4659.277811939155;4665.930011049837;4709.646776491777;4796.952030479685;4819.421669399694;4820.132376172288;5092.28707980883;5106.183010718267;4879.780409881564;4886.022795440926;4889.902201955941;4982.825601103944;5025.1959686450355;4759.857204283808;4761.714297142183;4763.571278584944;4766.523347665206;4770.069836381872;5141.2545180948555;5144.8713782155;5147.536721694991;5148.279240906276;5209;4709.1994360958715;4757.16749568831;4791.6406269529225;4930.778114984762;4971.6768410053955;5093.2105523643895;5102.183650615883;5102.69383836971;5108.821191258288;5109.412417572027;4853.9029219415415;4881.804727810848;5024.874378140576;5027.798888044437;5030.4718004608985;4911.18965370717;4918.311436398907;4925.162722337212;4933.260010998379;4937.950620493821;4077.0622756765515;4095.9590404096175;4113;4121.567235440296;4170.541240463546;3604.4232922732235;3612.151144481045;3613.1689711366535;3635.1093981974586;3639.9090022749115;4156.937645935313;4375.321825117112;4394.802233899482;4395.208862404797;4441.733495990186;4828;4912.164931961575;4913;4918.188498897642;5015.699058056528;4302.526722060571;4306.849260275938;4336.34954756789;4343.565643435694;4377.934330985037;4543.681942264076;4545.818087297326;4549.795259213343;4598.149342371672;4601.263797792337;4556.612687921432;4570.542945705402;4712.835050773207;4748.216544270295;4755.976220118947;4914.434839993446;4935.97532012352;4939.456659767495;4947.208446648519;4949.208126699711;4649;4654.650901182357;4655.837045703384;4664.300354946785;4666.066786642617;4879.536444037811;4986.426560945538;5026.597872170145;5042.445331013512;5108.208227724708;5099.158638824552;5103.055934652101;5104.846854594368;5105.6426050177615;5145.279660847409;4717.386739723814;4944.728039957693;5001.524855138756;5003.024410239968;5013.749312534285;4757;4784.210605250234;4799.688020278671;4801.327814105958;4822.493638167942;4858.174110401311;4912.680675756064;4919.1883339248625;4986.052649996489;4987.975060124676;5014.974925125351;5056.089903817348;5065.56942659878;5080.898382032413;5096.949030509648;4917.459079501324;4917.532834380728;4926.704397736147;4933.432655244693;4982.2775697524385 08:27:23 INFO - PROCESS | 15120 | |1;ext.html;538.9461053894611;552.2130963377189;552.6214543038019;575.3584753000406;603.5111559636695;26369.868150659244;38021;39942;40134.966258435394;42994.785026074904;39887.60112398875;40137;40141.39787903176;40171.19657606846;40271.798641006826;7574.545527268354;7752.496087754306;7753.651085701157;7759;7767.53394796314;6799.082123913257;6975.232724400312;6983.825404364906;7115.750948716796;7666.846663066735;6764;9661.130498255156;9868.753281167992;9872.111509964101;10165.491725413736;9771.609135634566;9840.36037657557;9840.852387214127;9848.16290615295;9852.605895764162;9563;9583.137517623447;9584.233261339075;9584.28117891154;9636.084571965723;31614.209644758834;31672.208194795083;31846.522302165486;31849.681503185137;31910.20224494383;3051.847407629632;3079.461094308487;3081.383723255358;3097.6282846058393;3146.9685303146903;26917;27120.32199195016;27276.45447091067;27669.723302766917;28043.719562804316;3349.179451034512;3369.882054128094;3425.6745609167338;3440.535527703753;3500.912477188065;157152;157216;157428;157902.21048894798;158094;45735;45877.54122458733;46061;46112;46136.3079553807;45941.31088033682;47364.7631761839;54839.1612587123;56062.1512988182;56114;12573.497060117676;12895.290759008158;12951.935240323926;12965.15726477776;13002.349882505838;9658.855117173249;9727.562259698188;9734.123928846439;9748;9804.558794854249;13955.092918960236;14122.717545649235;16566.917165414096;16602.83397166037;16762.497125086265;5659.8019069333195;5915;5947.145370222317;6059.848503787441;6105.419985101408;753.2881427051493;775.7478819383723;776.080344791424;785.6660919109356;789.4947233770391;22559.88720056422;23640.645390319158;23930.88034559816;26068.478630427286;26726.701214849963;832.1137988042715;848.0290067872252;890.9109089091039;916.8578870275118;924.4822899176482;12183.634490965285;12568.497260109676;12624;12796.2962037087;12834.614961551168;293675;294079;295237;295752;296211;41473;41505.169896601896;41583.7920810394;41631.167376652294;41658.583414166074;1119.630521927778;1155.5782139519015;1160.5357856857324;1161.6573110932295;1166.4750862112085;21224.363269101952;21261.57476850485;21409.464763381042;22118.668219977037;22657.546849062597;35557.82221088878;36019.45970810492;36064.81967590145;36136.096597585274;36140.09649758777;249321;251114;251314;251843;251868;19785.901070494554;20289.391318260477;20886.477838054172;37303.25393492169;38226.80886595549;40180;40848.79575602103;41006.79496602498;41674.16651666889;41696.79151604223;33075;34857.65142348544;34925.82537087298;35131.64868351284;35236.29527409386;33829;34760.65239347574;34909.65090349165;35269;36323.63676363203;34610.307793844484;35035.47446788351;35096;35428;36473;29634.407311854073;31767.523487148173;32297.35405291928;32656.51015234726;32746.672533274363;23909.641355379335;24458.51082978295;24480.14319498809;24817.751822481543;24911.377215569755 08:27:23 INFO - PROCESS | 15120 | |2;jquery.html;355.7278681808419;380.18829798380466;395.98812035638923;406.4817357868717;419.37513105472834;11796.646100616985;12009.759804803909;12805;16707.66584668309;17760.555986100353;17672.734908976345;18493;18543.16555754991;18779.154938027787;18838.71741923872;5104.234364845275;5372.54333381663;5375.462453754612;5385.3268341457315;5387.380451248117;5120.795168193268;5258.973705131469;5282.471752824726;5304.071787437202;5378.085725426687;5258.342707161605;5264.973675131619;5267.3679158500845;5268.736563171845;5272.209168624718;5091.159958606825;5242.344706911636;5263.526282634562;5267.789288428477;5289.550388217006;4778.04439112179;4778.474367819537;4781.306710526989;4782.665213435061;4788.0663270662235;20824.16703331865;21757.891210544003;21813;21977.560448791093;22083;2134.679798030292;2189.638709612912;2190.715207023077;2218.057325636614;2228.253535065755;7246.637668116573;12690.42893069814;13023.544175953892;13388;13633.250171240577;1660.3607611069704;1717;1734.9826501735074;1793.99536259695;1821.799602043774;62781.6860915697;71256;73150.63424682949;73203.63398182976;74623;10384.119463851977;10584.465252538339;10642.946785266178;10731.302465339728;10856.402897840695;15289;15303.846961530242;15304.923475382551;15309.6172595686;15314.693706126038;6810.62541560219;6955.6869940852785;7030.543014704028;7110.608916509642;7187.389071928945;6292.622442653455;6300.464460520907;6412.67936603168;6422.632609825364;6482.189726284215;4376.496702879146;4546.954530454719;4623.375844261049;4637.25803871379;4785.880352991203;1847.3349594146096;1852.19429548147;1852.3887117251275;1866.169554548213;1940.7768106667631;771.3751860992614;790.7509134622576;792.9920700792918;795.6936579416966;795.9880601790978;16430.75353869693;17539.615096225945;17580.736288955675;17921;17927.402333638864;787.6534324897027;802.0936341933694;802.1377019703842;815.5351449673748;816.1960468938091;9423.057694230522;9665.420074795395;9876.851847222297;9925.50372481373;10092.04125608066;426029;434203;435750;451613;452979;82979.17020829835;83143.5842820782;85064;85291;85718;822.6421506644629;832.9167083291622;841.8863453433707;842.5787106446777;843.409613270713;13787.448502059806;14077.436902524194;14216.21810800416;14325.928370358499;14778.704425911637;38275.80862095672;38859.611403885596;39256.607433925295;39937;39951.400728988505;49798.75100624474;50102;50200.24699629436;50656.240156398424;50667;7246.347828695442;7547.132406875191;7562;7672.079350477755;7865.488743231672;10837.729056773644;11275.830862537232;11378.487968041623;11619.350941738388;11753.059755219732;9129.817403652023;9385.953070234606;9665.420074795535;9832.754181145528;9926.15627671656;9481.431114133175;9769.511524423893;9862.358946668444;9878.061584149495;10168.135708464863;9520.285978551776;9680.177184939359;9707.563159657691;9948.651797187063;9978.950105249427;7388.4828062037295;7538.924610753822;7545.094588649177;7586.886196706943;7712.074551053909;7176.7846964591145;9417.858732118886;9429.62281508732;9459.95270023673;9737.318387712792 08:27:23 INFO - PROCESS | 15120 | |3;mootools.html;1021.8518314844353;1108.5898217659467;1122.9663110106699;1129.1418521923338;1182.0130191290273;6328.430441260286;7339;7562.168161502231;7563.999999999993;7573.356264717506;7598.39212862971;7645.853122031698;7651.617419129036;7659.463837531375;7676.92323076769;1727.6890159771235;2085.9895700521474;2121.9469513262216;2123.968140477894;2124.2140408048967;1771.3357490940898;2065.834733221346;2118.8093071623553;2123.2462475821094;2135.7010018597407;2069.3584988653565;2071.9482012949647;2072.6476498995207;2078.864873783207;2079.1683326669304;2036.3992622176493;2061.701053347271;2064.267185149273;2072.4404410809148;2072.6476498995207;2019.424464027745;2056.311135769519;2057.9279725209694;2067.58648270345;2109.8839563823985;7354.338109570111;7406.111266647983;7409.110906691231;7415.221401752792;7424.294692004251;4364.54172311909;4484.439445069366;4499.03270796777;4503.482099558527;4510.233260345749;2668.9065882694013;2877.079334612925;2928.311846716041;2932.5601159825983;3009.9849500752357;1079.1474734959372;1134.2740645986576;1158.1661203933154;1169.7601991591703;1211.684961909901;10293.845592316122;10327.535260913202;10351.79296414075;10356.948215258952;10365.533550990223;3876.9418458723144;4148.564400737879;4174.08170202555;4246.893827654333;4253.128108737763;8798.956005220061;8815;8816.338774591932;8817.603207855662;8824.294056475466;4558.088382323548;4560.840370587013;4561.087782443458;4561.566651168134;4565.977170114194;3404.9659503405146;3775.414810704345;3915.8433662653742;3922.4508568800393;3925.2542017017245;3511.9297614047578;3542.5040494330815;3639.1993761372455;3667.211549516867;3679.466477360768;1226.3193927370216;1241;1244.7821631214501;1254.0907841814794;1261.6215135459327;778.381186956371;783.9529628222211;795.1412474527497;798.7603718884311;802.9759107226793;4534.433195850519;4635.466921304093;4743.02767932573;4785.1625965455905;4900.534449227318;494.2043310270462;500.384527031753;505.2925903734727;506.4960364437408;507.520393228403;3079.5329807404273;3132.2482604174834;3147.8111313321274;3193.760467964912;3237.8704851806133;8898.555072246361;8987.41581797181;9061;9123;9124.087591240823;9020.503872287087;9072.773180670403;9122;9142.268618510503;9149.405288656217;6929.89605155913;7089.432845372357;7153.570785752871;7155.141383034069;7157.176924653628;4299.419578357007;4335.566443355639;4373.540778218368;4401.163778882003;4477.641788656965;8049.436539442417;8130.268275855202;8302.16978302189;8369.581520923808;8376.874346884924;8329.3336533076;8412.527807633518;8564.05795362522;8574.22831945128;8585.141485851365;5604.579656525695;6287.528435367274;6406.935930640634;6409.038644203407;6411.230652321751;6291.528135389955;6366.9681651591445;6832.93167068323;7220.097487814023;7273.781786546412;5866.120081987736;5893.204417403767;5921.733521991435;5923.378045305353;5930.199423077829;6040.36576159497;6092.055731361646;6093.9390606093375;6104.511639068953;6107.6640784757265;5624.325080990144;5645.463680950304;5677.886442271214;5699.145128230799;5701;3963.781991990468;3964.5044369453817;4060.878173654795;4071.1043570414463;4167.562405947332;4188.246115699081;4226.598473145046;4234.640055595309;4283.5288118306335;4316.266234740039 08:27:23 INFO - PROCESS | 15120 | |4;prototype.html;343.7696743182068;351.7063252184426;351.8856371679205;352.13726370392544;360.19676122247387;4755.619550435966;4779.402574678165;4801.279808028793;4824.831130910419;4848.927266091011;4738.147133515966;4742.288656701497;4762.952370476294;4768.54698803614;4778.139934811733;3591.730620203495;3715.7956312402807;3738.0467980664894;3738.4205448155444;3855.8457661693496;3718.4980027696183;3721.888343349704;3728.3661777497755;3734.1971476132626;3735.3463143949834;3687.542437192956;3696.168362118529;3698.389765688658;3705.3515634763944;3734.3091528067257;3613.6747692707518;3715.2198038411966;3732.234891847165;3738.5700644425965;3744.7004239660896;3209.1816586770456;3213.405519978788;3233.466478031122;3248.31785325082;3249.301400198945;4616.492185859553;4623.583877451012;4628.95371046292;4631.143238500888;4674.275487299474;4201.68487363449;4202.390653355277;4213.30480470722;4226.154769046203;4227.217964676544;4732.360998001063;4964.851054468371;4972.378452693413;4973.3286006389035;4980.277859710358;3482.4428091505238;3532;3539.911502212465;3544.5290204565076;3561.857525698969;4953.479884612136;5197.974010129963;5249;5249.763760630781;5253.579713622901;3455.2225749206227;3486.965130348715;3553.982230088833;3592.2815436912724;3618.4753210784816;3610.4223324268514;3628.582712987987;3638.599754027055;3642.8542858285373;3654.269146170723;4194.895127621773;4201.285781417167;4207.768572728468;4213.4410268200345;4251.213525497793;4691.81232750693;4819.518048195223;4923.827666031743;4961.578265847444;4968.8757781055765;1214.160546771624;1218.6959952850366;1219.7560487902456;1222.3826967381415;1222.5329604474666;777.5606782168057;815.1318845429598;815.7348861619997;815.8857759913617;825.591332290508;4202.054537729047;4204.1801848639225;4219.936700949488;4224;4232.814811852686;4985;4985.775640096204;4993.575546078551;5002.399712034579;5003.574696150868;4238.343056826136;4241.597048280409;4248.9787551062045;4249.2563801334645;4255.829766809355;2528.633348164506;2613.2421597736443;2634.170236375574;2644.2331723799884;2645.4444566641223;4621.4223222097235;4629.629629629621;4650.348951146842;4660.347551342815;4696.577308042292;4977.975110124498;5012.749362531858;5013.949860501421;5042.722650254198;5059.367579052619;4340.392345071693;4611.884702882455;4635.768211589407;4641.2109941310055;4657.883552911068;4934.383202099738;4947.307376967299;4947.82682606107;4952.059108769322;5018.082279366315;4896.510348965075;4937.530934561211;4940.456549779449;4941.43173535048;4947.505249475023;4534;4824.372831531879;4853.563179313879;4875.293082503088;4877.414710234794;3423.657634236656;3508.9298214035066;3514.121469632592;3527.171114788074;3531.9823400882833;4977.875553111201;5051.166557517969;5089.694618322765;5172.1984341508205;5176.256584010481;5009.749512524432;5023.673461225155;5034.597232221487;5040.294358789845;5060.671056381323;4895.877603059952;4991.57571606403;5012.8496145115705;5015.84952451427;5019.222020586815;4908.680935739166;4919.704817710949;4920.5571498565305;4927.655064145475;4927.852164435073;5038.992201559629;5040.470750571284;5053.393592768891;5058.822941197041;5062.468440813662;4677.3632705776345;4869.366982292279;4897.2654101885;4907.754612269444;4919.926201106915 08:27:23 INFO - PROCESS | 15120 | |5;yui.html;562.8761672432064;567.3305499510578;689.9306075582846;703.5145749432892;718.0808565036754;11809.822852657206;13046.739065218713;13057.67355816104;13059.673508162301;13077.738445231089;11117.499712512936;12606.117571769957;12697.80953285699;12721.42753576089;12733.617991460247;794.031281836159;808.8827120067587;828.2959484438239;835.8955130608674;835.9414840961135;822.4078663362369;834.2700137379793;836.1638361638362;836.7615229659548;836.8912041434605;873.4933738431727;875.4965894610592;875.8861348024748;876.1851478125341;876.2289185516736;867.7483529776357;867.9783005424883;868.7437206024238;869.2958703450225;869.6086760957594;631.3497097989078;635.4598591197491;637.2703254773259;638.856257342099;640.1422094393535;13599.116057456235;13761.449542018307;13780.65548361299;13894.513692020731;13937.930310348384;11700.648980530512;12601.597568656503;13027.934860325733;13299;13448.394822233024;9646.276529260334;9719.757006074904;9821.508924553744;9857.309988300822;10335.844962325571;2790.4000639862325;2813.071686343502;2891.768658507335;2923.093840909325;2924.1958461423064;62164.68917655428;62204;62786.68606656983;63399.68300158516;65331.67334163299;10295.691129265984;10352.5341359639;10410;10454.268201225841;10846.737992847404;19469.610607787767;19520.902395488214;19523.414297571097;19556;19684.409467715992;6070.696465176724;6090.512758979271;6129.957907155795;6198.845028874224;6337.461315788118;3273.3126043530647;3276.1973316537124;3308.6856748608843;3335.3996280669226;3354.8322583871195;2709.67483901933;2758.641376621026;2775.777937764974;2785.4846853332197;2788.7071857454675;449.2250867254026;461.3011287898836;468.26482422596195;480.9831655892097;507;530.2761730238274;541.8672425255759;558.6201383059478;563.3549585724322;563.605476166685;8121.269085782308;8137;8333.374664596873;8628.1337047354;8639.395242333103;593.7743657410157;607.1984979826586;621.6083867163749;623.329920335632;625.9812205633838;4079.1637714268504;4208.431861698693;4242.236397448427;4291.721038132511;4316.6608047793325;50252.24621630678;60830.69584652137;61027.38972610217;62623.06065409022;62710;42923.78538107289;48337.75831120822;52099.218511722356;53423.732881335345;53596.464035359924;48005.25365293058;48553.75723121362;48689.26966095441;48909.75545122252;49888.50111498839;7870.527768333689;8171.632276547689;8231.218034286732;8368.87446688288;8462.788430289293;32814.34371312608;33407.66592334046;33457.66542334643;34379.48430773491;34545.82727086348;39692.80153599329;42633.147337053706;43030;43876.341854872844;44096;8864.246539044254;8948.507832069039;9243.352965292364;9264.212541934012;9352.625894964125;11096.223264371416;11145.052670522771;11254.831177532507;11382;11576.652700419001;10240.487975601041;10691.732706682395;10861.076808471369;11040.392778397265;11059.391733454737;9720.204573043424;9780.510974451392;9937.20502359795;10118.645847395306;10119.696409107739;8551.74344769658;8817.911820881965;8975;9197.448153110834;9209.033051529494;8132.87800683002;8365.790855228668;8576.614052367784;8578.227959483676;8843.734687959372;7273.599952002691;7356.583770930432;7730.226977302224;7812.617151768765;8304.211099945496 08:27:23 INFO - PROCESS | 15120 | __end_tp_report 08:27:23 INFO - PROCESS | 15120 | __start_cc_report 08:27:23 INFO - PROCESS | 15120 | _x_x_mozilla_cycle_collect,1513 08:27:23 INFO - PROCESS | 15120 | __end_cc_report 08:27:23 INFO - PROCESS | 15120 | __startTimestamp1487608043316__endTimestamp 08:27:23 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | ------- Summary: start ------- 08:27:23 INFO - PROCESS | 15120 | Number of tests: 6 08:27:23 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | [#0] dojo.html Cycles:165 Average:4636.41 Median:4761.71 stddev:497.60 (10.4%) stddev-sans-first:471.57 08:27:23 INFO - PROCESS | 15120 | Values: 2555.0 2573.9 2595.6 2597.5 2611.5 4496.3 4547.1 4724.1 4762.6 4774.9 4698.2 4711.5 4750.1 4756.9 4827.0 4322.8 4332.0 4368.7 4409.9 4426.4 4407.9 4422.2 4454.7 4469.4 4487.2 4277.6 4314.7 4318.9 4359.6 4374.1 4281.0 4288.4 4354.8 4370.1 4381.4 4266.9 4274.3 4312.1 4335.5 4346.2 4588.8 4649.5 4659.3 4665.9 4709.6 4797.0 4819.4 4820.1 5092.3 5106.2 4879.8 4886.0 4889.9 4982.8 5025.2 4759.9 4761.7 4763.6 4766.5 4770.1 5141.3 5144.9 5147.5 5148.3 5209.0 4709.2 4757.2 4791.6 4930.8 4971.7 5093.2 5102.2 5102.7 5108.8 5109.4 4853.9 4881.8 5024.9 5027.8 5030.5 4911.2 4918.3 4925.2 4933.3 4938.0 4077.1 4096.0 4113.0 4121.6 4170.5 3604.4 3612.2 3613.2 3635.1 3639.9 4156.9 4375.3 4394.8 4395.2 4441.7 4828.0 4912.2 4913.0 4918.2 5015.7 4302.5 4306.8 4336.3 4343.6 4377.9 4543.7 4545.8 4549.8 4598.1 4601.3 4556.6 4570.5 4712.8 4748.2 4756.0 4914.4 4936.0 4939.5 4947.2 4949.2 4649.0 4654.7 4655.8 4664.3 4666.1 4879.5 4986.4 5026.6 5042.4 5108.2 5099.2 5103.1 5104.8 5105.6 5145.3 4717.4 4944.7 5001.5 5003.0 5013.7 4757.0 4784.2 4799.7 4801.3 4822.5 4858.2 4912.7 4919.2 4986.1 4988.0 5015.0 5056.1 5065.6 5080.9 5096.9 4917.5 4917.5 4926.7 4933.4 4982.3 08:27:23 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | [#1] ext.html Cycles:180 Average:38950.67 Median:35252.65 stddev:63188.50 (179.2%) stddev-sans-first:63299.94 08:27:23 INFO - PROCESS | 15120 | Values: 538.9 552.2 552.6 575.4 603.5 26369.9 38021.0 39942.0 40135.0 42994.8 39887.6 40137.0 40141.4 40171.2 40271.8 7574.5 7752.5 7753.7 7759.0 7767.5 6799.1 6975.2 6983.8 7115.8 7666.8 6764.0 9661.1 9868.8 9872.1 10165.5 9771.6 9840.4 9840.9 9848.2 9852.6 9563.0 9583.1 9584.2 9584.3 9636.1 31614.2 31672.2 31846.5 31849.7 31910.2 3051.8 3079.5 3081.4 3097.6 3147.0 26917.0 27120.3 27276.5 27669.7 28043.7 3349.2 3369.9 3425.7 3440.5 3500.9 157152.0 157216.0 157428.0 157902.2 158094.0 45735.0 45877.5 46061.0 46112.0 46136.3 45941.3 47364.8 54839.2 56062.2 56114.0 12573.5 12895.3 12951.9 12965.2 13002.3 9658.9 9727.6 9734.1 9748.0 9804.6 13955.1 14122.7 16566.9 16602.8 16762.5 5659.8 5915.0 5947.1 6059.8 6105.4 753.3 775.7 776.1 785.7 789.5 22559.9 23640.6 23930.9 26068.5 26726.7 832.1 848.0 890.9 916.9 924.5 12183.6 12568.5 12624.0 12796.3 12834.6 293675.0 294079.0 295237.0 295752.0 296211.0 41473.0 41505.2 41583.8 41631.2 41658.6 1119.6 1155.6 1160.5 1161.7 1166.5 21224.4 21261.6 21409.5 22118.7 22657.5 35557.8 36019.5 36064.8 36136.1 36140.1 249321.0 251114.0 251314.0 251843.0 251868.0 19785.9 20289.4 20886.5 37303.3 38226.8 40180.0 40848.8 41006.8 41674.2 41696.8 33075.0 34857.7 34925.8 35131.6 35236.3 33829.0 34760.7 34909.7 35269.0 36323.6 34610.3 35035.5 35096.0 35428.0 36473.0 29634.4 31767.5 32297.4 32656.5 32746.7 23909.6 24458.5 24480.1 24817.8 24911.4 08:27:23 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | [#2] jquery.html Cycles:180 Average:26004.49 Median:27879.08 stddev:72604.99 (260.4%) stddev-sans-first:72783.12 08:27:23 INFO - PROCESS | 15120 | Values: 355.7 380.2 396.0 406.5 419.4 11796.6 12009.8 12805.0 16707.7 17760.6 17672.7 18493.0 18543.2 18779.2 18838.7 5104.2 5372.5 5375.5 5385.3 5387.4 5120.8 5259.0 5282.5 5304.1 5378.1 5258.3 5265.0 5267.4 5268.7 5272.2 5091.2 5242.3 5263.5 5267.8 5289.6 4778.0 4778.5 4781.3 4782.7 4788.1 20824.2 21757.9 21813.0 21977.6 22083.0 2134.7 2189.6 2190.7 2218.1 2228.3 7246.6 12690.4 13023.5 13388.0 13633.3 1660.4 1717.0 1735.0 1794.0 1821.8 62781.7 71256.0 73150.6 73203.6 74623.0 10384.1 10584.5 10642.9 10731.3 10856.4 15289.0 15303.8 15304.9 15309.6 15314.7 6810.6 6955.7 7030.5 7110.6 7187.4 6292.6 6300.5 6412.7 6422.6 6482.2 4376.5 4547.0 4623.4 4637.3 4785.9 1847.3 1852.2 1852.4 1866.2 1940.8 771.4 790.8 793.0 795.7 796.0 16430.8 17539.6 17580.7 17921.0 17927.4 787.7 802.1 802.1 815.5 816.2 9423.1 9665.4 9876.9 9925.5 10092.0 426029.0 434203.0 435750.0 451613.0 452979.0 82979.2 83143.6 85064.0 85291.0 85718.0 822.6 832.9 841.9 842.6 843.4 13787.4 14077.4 14216.2 14325.9 14778.7 38275.8 38859.6 39256.6 39937.0 39951.4 49798.8 50102.0 50200.2 50656.2 50667.0 7246.3 7547.1 7562.0 7672.1 7865.5 10837.7 11275.8 11378.5 11619.4 11753.1 9129.8 9386.0 9665.4 9832.8 9926.2 9481.4 9769.5 9862.4 9878.1 10168.1 9520.3 9680.2 9707.6 9948.7 9979.0 7388.5 7538.9 7545.1 7586.9 7712.1 7176.8 9417.9 9429.6 9460.0 9737.3 08:27:23 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | [#3] mootools.html Cycles:180 Average:4811.74 Median:4546.26 stddev:2734.07 (60.1%) stddev-sans-first:2726.90 08:27:23 INFO - PROCESS | 15120 | Values: 1021.9 1108.6 1123.0 1129.1 1182.0 6328.4 7339.0 7562.2 7564.0 7573.4 7598.4 7645.9 7651.6 7659.5 7676.9 1727.7 2086.0 2121.9 2124.0 2124.2 1771.3 2065.8 2118.8 2123.2 2135.7 2069.4 2071.9 2072.6 2078.9 2079.2 2036.4 2061.7 2064.3 2072.4 2072.6 2019.4 2056.3 2057.9 2067.6 2109.9 7354.3 7406.1 7409.1 7415.2 7424.3 4364.5 4484.4 4499.0 4503.5 4510.2 2668.9 2877.1 2928.3 2932.6 3010.0 1079.1 1134.3 1158.2 1169.8 1211.7 10293.8 10327.5 10351.8 10356.9 10365.5 3876.9 4148.6 4174.1 4246.9 4253.1 8799.0 8815.0 8816.3 8817.6 8824.3 4558.1 4560.8 4561.1 4561.6 4566.0 3405.0 3775.4 3915.8 3922.5 3925.3 3511.9 3542.5 3639.2 3667.2 3679.5 1226.3 1241.0 1244.8 1254.1 1261.6 778.4 784.0 795.1 798.8 803.0 4534.4 4635.5 4743.0 4785.2 4900.5 494.2 500.4 505.3 506.5 507.5 3079.5 3132.2 3147.8 3193.8 3237.9 8898.6 8987.4 9061.0 9123.0 9124.1 9020.5 9072.8 9122.0 9142.3 9149.4 6929.9 7089.4 7153.6 7155.1 7157.2 4299.4 4335.6 4373.5 4401.2 4477.6 8049.4 8130.3 8302.2 8369.6 8376.9 8329.3 8412.5 8564.1 8574.2 8585.1 5604.6 6287.5 6406.9 6409.0 6411.2 6291.5 6367.0 6832.9 7220.1 7273.8 5866.1 5893.2 5921.7 5923.4 5930.2 6040.4 6092.1 6093.9 6104.5 6107.7 5624.3 5645.5 5677.9 5699.1 5701.0 3963.8 3964.5 4060.9 4071.1 4167.6 4188.2 4226.6 4234.6 4283.5 4316.3 08:27:23 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | [#4] prototype.html Cycles:180 Average:4089.97 Median:4645.78 stddev:1189.32 (25.6%) stddev-sans-first:1158.94 08:27:23 INFO - PROCESS | 15120 | Values: 343.8 351.7 351.9 352.1 360.2 4755.6 4779.4 4801.3 4824.8 4848.9 4738.1 4742.3 4763.0 4768.5 4778.1 3591.7 3715.8 3738.0 3738.4 3855.8 3718.5 3721.9 3728.4 3734.2 3735.3 3687.5 3696.2 3698.4 3705.4 3734.3 3613.7 3715.2 3732.2 3738.6 3744.7 3209.2 3213.4 3233.5 3248.3 3249.3 4616.5 4623.6 4629.0 4631.1 4674.3 4201.7 4202.4 4213.3 4226.2 4227.2 4732.4 4964.9 4972.4 4973.3 4980.3 3482.4 3532.0 3539.9 3544.5 3561.9 4953.5 5198.0 5249.0 5249.8 5253.6 3455.2 3487.0 3554.0 3592.3 3618.5 3610.4 3628.6 3638.6 3642.9 3654.3 4194.9 4201.3 4207.8 4213.4 4251.2 4691.8 4819.5 4923.8 4961.6 4968.9 1214.2 1218.7 1219.8 1222.4 1222.5 777.6 815.1 815.7 815.9 825.6 4202.1 4204.2 4219.9 4224.0 4232.8 4985.0 4985.8 4993.6 5002.4 5003.6 4238.3 4241.6 4249.0 4249.3 4255.8 2528.6 2613.2 2634.2 2644.2 2645.4 4621.4 4629.6 4650.3 4660.3 4696.6 4978.0 5012.7 5013.9 5042.7 5059.4 4340.4 4611.9 4635.8 4641.2 4657.9 4934.4 4947.3 4947.8 4952.1 5018.1 4896.5 4937.5 4940.5 4941.4 4947.5 4534.0 4824.4 4853.6 4875.3 4877.4 3423.7 3508.9 3514.1 3527.2 3532.0 4977.9 5051.2 5089.7 5172.2 5176.3 5009.7 5023.7 5034.6 5040.3 5060.7 4895.9 4991.6 5012.8 5015.8 5019.2 4908.7 4919.7 4920.6 4927.7 4927.9 5039.0 5040.5 5053.4 5058.8 5062.5 4677.4 4869.4 4897.3 4907.8 4919.9 08:27:23 INFO - PROCESS | 15120 | 08:27:23 INFO - PROCESS | 15120 | [#5] yui.html Cycles:180 Average:13837.99 Median:550.24 stddev:17249.70 (3134.9%) stddev-sans-first:17269.28 08:27:23 INFO - PROCESS | 15120 | Values: 562.9 567.3 689.9 703.5 718.1 11809.8 13046.7 13057.7 13059.7 13077.7 11117.5 12606.1 12697.8 12721.4 12733.6 794.0 808.9 828.3 835.9 835.9 822.4 834.3 836.2 836.8 836.9 873.5 875.5 875.9 876.2 876.2 867.7 868.0 868.7 869.3 869.6 631.3 635.5 637.3 638.9 640.1 13599.1 13761.4 13780.7 13894.5 13937.9 11700.6 12601.6 13027.9 13299.0 13448.4 9646.3 9719.8 9821.5 9857.3 10335.8 2790.4 2813.1 2891.8 2923.1 2924.2 62164.7 62204.0 62786.7 63399.7 65331.7 10295.7 10352.5 10410.0 10454.3 10846.7 19469.6 19520.9 19523.4 19556.0 19684.4 6070.7 6090.5 6130.0 6198.8 6337.5 3273.3 3276.2 3308.7 3335.4 3354.8 2709.7 2758.6 2775.8 2785.5 2788.7 449.2 461.3 468.3 481.0 507.0 530.3 541.9 558.6 563.4 563.6 8121.3 8137.0 8333.4 8628.1 8639.4 593.8 607.2 621.6 623.3 626.0 4079.2 4208.4 4242.2 4291.7 4316.7 50252.2 60830.7 61027.4 62623.1 62710.0 42923.8 48337.8 52099.2 53423.7 53596.5 48005.3 48553.8 48689.3 48909.8 49888.5 7870.5 8171.6 8231.2 8368.9 8462.8 32814.3 33407.7 33457.7 34379.5 34545.8 39692.8 42633.1 43030.0 43876.3 44096.0 8864.2 8948.5 9243.4 9264.2 9352.6 11096.2 11145.1 11254.8 11382.0 11576.7 10240.5 10691.7 10861.1 11040.4 11059.4 9720.2 9780.5 9937.2 10118.6 10119.7 8551.7 8817.9 8975.0 9197.4 9209.0 8132.9 8365.8 8576.6 8578.2 8843.7 7273.6 7356.6 7730.2 7812.6 8304.2 08:27:23 INFO - PROCESS | 15120 | -------- Summary: end -------- 08:27:23 INFO - PROCESS | 15120 | 08:27:24 INFO - TEST-INFO | 15120: exit 0 08:27:24 INFO - TEST-OK | dromaeo_css | took 1124165ms 08:27:24 INFO - TEST-START | kraken 08:27:24 INFO - operating with platform_type : linux_ 08:27:24 INFO - Initialising browser for kraken test... 08:27:24 INFO - TEST-INFO | started process 15444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpleDJUs/profile http://localhost:33148/getInfo.html) 08:27:27 INFO - PROCESS | 15444 | Iterating over profile /tmp/tmpleDJUs/profile 08:27:28 INFO - PROCESS | 15444 | __metrics Screen width/height:1600/1200 08:27:28 INFO - PROCESS | 15444 | colorDepth:24 08:27:28 INFO - PROCESS | 15444 | Browser inner width/height: 1024/697 08:27:28 INFO - PROCESS | 15444 | __metrics 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/frequencyCap.json = Mon Feb 20 2017 08:27:27 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/addons.json = Mon Feb 20 2017 08:27:27 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/storage.sqlite = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/safebrowsing = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/.parentlock = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/extensions.json = Mon Feb 20 2017 08:27:27 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/cookies.sqlite = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/sessionCheckpoints.json = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | Stat failure 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/startupCache = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/safebrowsing-backup = Mon Feb 20 2017 08:27:27 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/webappsstore.sqlite-wal = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/minidumps = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/crashes = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/bookmarkbackups = Mon Feb 20 2017 08:27:27 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/key3.db = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/extensions = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/content-prefs.sqlite = Mon Feb 20 2017 08:27:26 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/secmod.db = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/webappsstore.sqlite = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/webappsstore.sqlite-shm = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/user.js = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/places.sqlite-shm = Mon Feb 20 2017 08:27:26 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/thumbnails = Mon Feb 20 2017 08:27:27 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/prefs.js = Mon Feb 20 2017 08:07:58 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/directoryLinks.json = Mon Feb 20 2017 08:27:27 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/extensions.ini = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/places.sqlite-wal = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/compatibility.ini = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/localstore.rdf = Mon Feb 20 2017 08:07:58 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/gmp = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/storage.sqlite-journal = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/cache2 = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/permissions.sqlite = Mon Feb 20 2017 08:27:25 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/mimeTypes.rdf = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/pluginreg.dat = Mon Feb 20 2017 08:27:27 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/places.sqlite = Mon Feb 20 2017 08:27:28 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/blocklist.xml = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:28 INFO - PROCESS | 15444 | No birth date. Using mtime. 08:27:28 INFO - PROCESS | 15444 | Using date: /tmp/tmpleDJUs/profile/cert8.db = Mon Feb 20 2017 08:27:24 GMT-0800 (PST) 08:27:29 INFO - PROCESS | 15444 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:27:29 INFO - PROCESS | 15444 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:27:31 INFO - TEST-INFO | 15444: exit 0 08:27:31 INFO - Browser initialized. 08:27:31 INFO - Running cycle 1/1 for kraken test... 08:27:31 INFO - TEST-INFO | started process 15530 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpleDJUs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:27:43 INFO - PROCESS | 15530 | RSS: Main: 146911232 08:27:43 INFO - PROCESS | 15530 | 08:27:45 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33148/tests/kraken/driver.html?testName=audio-beat-detection) 08:27:46 INFO - PROCESS | 15530 | RSS: Main: 201641984 08:27:46 INFO - PROCESS | 15530 | 08:27:49 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33148/tests/kraken/driver.html?testName=audio-dft) 08:27:50 INFO - PROCESS | 15530 | RSS: Main: 181157888 08:27:50 INFO - PROCESS | 15530 | 08:27:53 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33148/tests/kraken/driver.html?testName=audio-fft) 08:27:53 INFO - PROCESS | 15530 | RSS: Main: 263577600 08:27:53 INFO - PROCESS | 15530 | 08:27:56 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33148/tests/kraken/driver.html?testName=audio-oscillator) 08:27:56 INFO - PROCESS | 15530 | RSS: Main: 174538752 08:27:56 INFO - PROCESS | 15530 | 08:27:58 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33148/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:27:59 INFO - PROCESS | 15530 | RSS: Main: 177750016 08:27:59 INFO - PROCESS | 15530 | 08:28:02 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33148/tests/kraken/driver.html?testName=imaging-darkroom) 08:28:03 INFO - PROCESS | 15530 | RSS: Main: 216629248 08:28:03 INFO - PROCESS | 15530 | 08:28:06 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33148/tests/kraken/driver.html?testName=imaging-desaturate) 08:28:07 INFO - PROCESS | 15530 | RSS: Main: 211603456 08:28:07 INFO - PROCESS | 15530 | 08:28:10 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33148/tests/kraken/driver.html?testName=json-parse-financial) 08:28:10 INFO - PROCESS | 15530 | RSS: Main: 212647936 08:28:10 INFO - PROCESS | 15530 | 08:28:12 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33148/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:28:13 INFO - PROCESS | 15530 | RSS: Main: 176435200 08:28:13 INFO - PROCESS | 15530 | 08:28:15 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:28:15 INFO - PROCESS | 15530 | RSS: Main: 184180736 08:28:15 INFO - PROCESS | 15530 | 08:28:17 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:28:18 INFO - PROCESS | 15530 | RSS: Main: 192458752 08:28:18 INFO - PROCESS | 15530 | 08:28:21 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:28:22 INFO - PROCESS | 15530 | RSS: Main: 202592256 08:28:22 INFO - PROCESS | 15530 | 08:28:25 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:28:25 INFO - PROCESS | 15530 | RSS: Main: 198017024 08:28:25 INFO - PROCESS | 15530 | 08:28:27 INFO - PROCESS | 15530 | Cycle 1(1): loaded http://localhost:33148/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33148/tests/kraken/driver.html?testName=ai-astar) 08:28:28 INFO - PROCESS | 15530 | RSS: Main: 250765312 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | __start_tp_report 08:28:28 INFO - PROCESS | 15530 | _x_x_mozilla_page_load 08:28:28 INFO - PROCESS | 15530 | _x_x_mozilla_page_load_details 08:28:28 INFO - PROCESS | 15530 | |i|pagename|runs| 08:28:28 INFO - PROCESS | 15530 | |0;ai-astar;116;115;115;118;116;119;116;114;117;115 08:28:28 INFO - PROCESS | 15530 | |1;audio-beat-detection;152;127;127;167;128;136;134;126;155;128 08:28:28 INFO - PROCESS | 15530 | |2;audio-dft;163;161;161;163;163;162;163;162;163;163 08:28:28 INFO - PROCESS | 15530 | |3;audio-fft;77;78;77;116;78;77;77;76;92;83 08:28:28 INFO - PROCESS | 15530 | |4;audio-oscillator;84;86;112;85;85;85;108;85;84;116 08:28:28 INFO - PROCESS | 15530 | |5;imaging-gaussian-blur;114;113;115;113;113;114;114;114;113;116 08:28:28 INFO - PROCESS | 15530 | |6;imaging-darkroom;149;150;149;151;150;149;149;150;149;149 08:28:28 INFO - PROCESS | 15530 | |7;imaging-desaturate;96;95;95;96;96;95;96;96;95;95 08:28:28 INFO - PROCESS | 15530 | |8;json-parse-financial;62;63;61;62;63;63;62;63;63;63 08:28:28 INFO - PROCESS | 15530 | |9;json-stringify-tinderbox;65;66;66;65;66;65;66;67;66;66 08:28:28 INFO - PROCESS | 15530 | |10;stanford-crypto-aes;89;89;88;88;88;89;91;89;89;89 08:28:28 INFO - PROCESS | 15530 | |11;stanford-crypto-ccm;138;143;148;138;150;138;146;146;177;147 08:28:28 INFO - PROCESS | 15530 | |12;stanford-crypto-pbkdf2;192;189;187;196;190;188;195;185;184;184 08:28:28 INFO - PROCESS | 15530 | |13;stanford-crypto-sha256-iterative;70;70;76;76;70;71;69;70;71;70 08:28:28 INFO - PROCESS | 15530 | __end_tp_report 08:28:28 INFO - PROCESS | 15530 | __start_cc_report 08:28:28 INFO - PROCESS | 15530 | _x_x_mozilla_cycle_collect,2008 08:28:28 INFO - PROCESS | 15530 | __end_cc_report 08:28:28 INFO - PROCESS | 15530 | __startTimestamp1487608108036__endTimestamp 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | ------- Summary: start ------- 08:28:28 INFO - PROCESS | 15530 | Number of tests: 14 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#0] ai-astar Cycles:10 Average:116.10 Median:116.00 stddev:1.52 (1.3%) stddev-sans-first:1.62 08:28:28 INFO - PROCESS | 15530 | Values: 116.0 115.0 115.0 118.0 116.0 119.0 116.0 114.0 117.0 115.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:135.00 stddev:14.65 (10.9%) stddev-sans-first:14.64 08:28:28 INFO - PROCESS | 15530 | Values: 152.0 127.0 127.0 167.0 128.0 136.0 134.0 126.0 155.0 128.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 08:28:28 INFO - PROCESS | 15530 | Values: 163.0 161.0 161.0 163.0 163.0 162.0 163.0 162.0 163.0 163.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#3] audio-fft Cycles:10 Average:83.10 Median:77.50 stddev:12.53 (16.2%) stddev-sans-first:13.09 08:28:28 INFO - PROCESS | 15530 | Values: 77.0 78.0 77.0 116.0 78.0 77.0 77.0 76.0 92.0 83.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:85.00 stddev:13.26 (15.6%) stddev-sans-first:13.66 08:28:28 INFO - PROCESS | 15530 | Values: 84.0 86.0 112.0 85.0 85.0 85.0 108.0 85.0 84.0 116.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.99 (0.9%) stddev-sans-first:1.05 08:28:28 INFO - PROCESS | 15530 | Values: 114.0 113.0 115.0 113.0 113.0 114.0 114.0 114.0 113.0 116.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:149.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 08:28:28 INFO - PROCESS | 15530 | Values: 149.0 150.0 149.0 151.0 150.0 149.0 149.0 150.0 149.0 149.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:28:28 INFO - PROCESS | 15530 | Values: 96.0 95.0 95.0 96.0 96.0 95.0 96.0 96.0 95.0 95.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 08:28:28 INFO - PROCESS | 15530 | Values: 62.0 63.0 61.0 62.0 63.0 63.0 62.0 63.0 63.0 63.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 08:28:28 INFO - PROCESS | 15530 | Values: 65.0 66.0 66.0 65.0 66.0 65.0 66.0 67.0 66.0 66.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:89.00 stddev:0.88 (1.0%) stddev-sans-first:0.93 08:28:28 INFO - PROCESS | 15530 | Values: 89.0 89.0 88.0 88.0 88.0 89.0 91.0 89.0 89.0 89.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:146.50 stddev:11.41 (7.8%) stddev-sans-first:11.61 08:28:28 INFO - PROCESS | 15530 | Values: 138.0 143.0 148.0 138.0 150.0 138.0 146.0 146.0 177.0 147.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:189.50 stddev:4.29 (2.3%) stddev-sans-first:4.42 08:28:28 INFO - PROCESS | 15530 | Values: 192.0 189.0 187.0 196.0 190.0 188.0 195.0 185.0 184.0 184.0 08:28:28 INFO - PROCESS | 15530 | 08:28:28 INFO - PROCESS | 15530 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:70.50 stddev:2.54 (3.6%) stddev-sans-first:2.65 08:28:28 INFO - PROCESS | 15530 | Values: 70.0 70.0 76.0 76.0 70.0 71.0 69.0 70.0 71.0 70.0 08:28:28 INFO - PROCESS | 15530 | -------- Summary: end -------- 08:28:28 INFO - PROCESS | 15530 | 08:28:29 INFO - TEST-INFO | 15530: exit 0 08:28:29 INFO - TEST-OK | kraken | took 64953ms 08:28:29 INFO - SUITE-END | took 1189s 08:28:29 INFO - Completed test suite (00:19:49) 08:28:29 INFO - javascript benchmark 08:28:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6117.329182102189, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2555.0, 2573.871306434678, 2595.558755011648, 2597.454534547745, 2611.5429799785034, 4496.258117410632, 4547.113312903985, 4724.149653062447, 4762.595179409755, 4774.904501909959, 4698.177818881699, 4711.528847115295, 4750.121227572901, 4756.857294281169, 4827.034593081389, 4322.762248076347, 4331.978340108295, 4368.650507959355, 4409.933850992237, 4426.44669416323, 4407.933880991788, 4422.248217802965, 4454.665900057508, 4469.418975533193, 4487.214737420938, 4277.550857159985, 4314.676399270067, 4318.913621727579, 4359.564043595647, 4374.081442896995, 4281.036766727492, 4288.421063156465, 4354.847580334673, 4370.147821174872, 4381.364702118175, 4266.935995960063, 4274.273373526508, 4312.072904325553, 4335.544767799367, 4346.174226896879, 4588.7935042923145, 4649.465311489154, 4659.277811939155, 4665.930011049837, 4709.646776491777, 4796.952030479685, 4819.421669399694, 4820.132376172288, 5092.28707980883, 5106.183010718267, 4879.780409881564, 4886.022795440926, 4889.902201955941, 4982.825601103944, 5025.1959686450355, 4759.857204283808, 4761.714297142183, 4763.571278584944, 4766.523347665206, 4770.069836381872, 5141.2545180948555, 5144.8713782155, 5147.536721694991, 5148.279240906276, 5209.0, 4709.1994360958715, 4757.16749568831, 4791.6406269529225, 4930.778114984762, 4971.6768410053955, 5093.2105523643895, 5102.183650615883, 5102.69383836971, 5108.821191258288, 5109.412417572027, 4853.9029219415415, 4881.804727810848, 5024.874378140576, 5027.798888044437, 5030.4718004608985, 4911.18965370717, 4918.311436398907, 4925.162722337212, 4933.260010998379, 4937.950620493821, 4077.0622756765515, 4095.9590404096175, 4113.0, 4121.567235440296, 4170.541240463546, 3604.4232922732235, 3612.151144481045, 3613.1689711366535, 3635.1093981974586, 3639.9090022749115, 4156.937645935313, 4375.321825117112, 4394.802233899482, 4395.208862404797, 4441.733495990186, 4828.0, 4912.164931961575, 4913.0, 4918.188498897642, 5015.699058056528, 4302.526722060571, 4306.849260275938, 4336.34954756789, 4343.565643435694, 4377.934330985037, 4543.681942264076, 4545.818087297326, 4549.795259213343, 4598.149342371672, 4601.263797792337, 4556.612687921432, 4570.542945705402, 4712.835050773207, 4748.216544270295, 4755.976220118947, 4914.434839993446, 4935.97532012352, 4939.456659767495, 4947.208446648519, 4949.208126699711, 4649.0, 4654.650901182357, 4655.837045703384, 4664.300354946785, 4666.066786642617, 4879.536444037811, 4986.426560945538, 5026.597872170145, 5042.445331013512, 5108.208227724708, 5099.158638824552, 5103.055934652101, 5104.846854594368, 5105.6426050177615, 5145.279660847409, 4717.386739723814, 4944.728039957693, 5001.524855138756, 5003.024410239968, 5013.749312534285, 4757.0, 4784.210605250234, 4799.688020278671, 4801.327814105958, 4822.493638167942, 4858.174110401311, 4912.680675756064, 4919.1883339248625, 4986.052649996489, 4987.975060124676, 5014.974925125351, 5056.089903817348, 5065.56942659878, 5080.898382032413, 5096.949030509648, 4917.459079501324, 4917.532834380728, 4926.704397736147, 4933.432655244693, 4982.2775697524385], "value": 4603.789660614946, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.9461053894611, 552.2130963377189, 552.6214543038019, 575.3584753000406, 603.5111559636695, 26369.868150659244, 38021.0, 39942.0, 40134.966258435394, 42994.785026074904, 39887.60112398875, 40137.0, 40141.39787903176, 40171.19657606846, 40271.798641006826, 7574.545527268354, 7752.496087754306, 7753.651085701157, 7759.0, 7767.53394796314, 6799.082123913257, 6975.232724400312, 6983.825404364906, 7115.750948716796, 7666.846663066735, 6764.0, 9661.130498255156, 9868.753281167992, 9872.111509964101, 10165.491725413736, 9771.609135634566, 9840.36037657557, 9840.852387214127, 9848.16290615295, 9852.605895764162, 9563.0, 9583.137517623447, 9584.233261339075, 9584.28117891154, 9636.084571965723, 31614.209644758834, 31672.208194795083, 31846.522302165486, 31849.681503185137, 31910.20224494383, 3051.847407629632, 3079.461094308487, 3081.383723255358, 3097.6282846058393, 3146.9685303146903, 26917.0, 27120.32199195016, 27276.45447091067, 27669.723302766917, 28043.719562804316, 3349.179451034512, 3369.882054128094, 3425.6745609167338, 3440.535527703753, 3500.912477188065, 157152.0, 157216.0, 157428.0, 157902.21048894798, 158094.0, 45735.0, 45877.54122458733, 46061.0, 46112.0, 46136.3079553807, 45941.31088033682, 47364.7631761839, 54839.1612587123, 56062.1512988182, 56114.0, 12573.497060117676, 12895.290759008158, 12951.935240323926, 12965.15726477776, 13002.349882505838, 9658.855117173249, 9727.562259698188, 9734.123928846439, 9748.0, 9804.558794854249, 13955.092918960236, 14122.717545649235, 16566.917165414096, 16602.83397166037, 16762.497125086265, 5659.8019069333195, 5915.0, 5947.145370222317, 6059.848503787441, 6105.419985101408, 753.2881427051493, 775.7478819383723, 776.080344791424, 785.6660919109356, 789.4947233770391, 22559.88720056422, 23640.645390319158, 23930.88034559816, 26068.478630427286, 26726.701214849963, 832.1137988042715, 848.0290067872252, 890.9109089091039, 916.8578870275118, 924.4822899176482, 12183.634490965285, 12568.497260109676, 12624.0, 12796.2962037087, 12834.614961551168, 293675.0, 294079.0, 295237.0, 295752.0, 296211.0, 41473.0, 41505.169896601896, 41583.7920810394, 41631.167376652294, 41658.583414166074, 1119.630521927778, 1155.5782139519015, 1160.5357856857324, 1161.6573110932295, 1166.4750862112085, 21224.363269101952, 21261.57476850485, 21409.464763381042, 22118.668219977037, 22657.546849062597, 35557.82221088878, 36019.45970810492, 36064.81967590145, 36136.096597585274, 36140.09649758777, 249321.0, 251114.0, 251314.0, 251843.0, 251868.0, 19785.901070494554, 20289.391318260477, 20886.477838054172, 37303.25393492169, 38226.80886595549, 40180.0, 40848.79575602103, 41006.79496602498, 41674.16651666889, 41696.79151604223, 33075.0, 34857.65142348544, 34925.82537087298, 35131.64868351284, 35236.29527409386, 33829.0, 34760.65239347574, 34909.65090349165, 35269.0, 36323.63676363203, 34610.307793844484, 35035.47446788351, 35096.0, 35428.0, 36473.0, 29634.407311854073, 31767.523487148173, 32297.35405291928, 32656.51015234726, 32746.672533274363, 23909.641355379335, 24458.51082978295, 24480.14319498809, 24817.751822481543, 24911.377215569755], "value": 16219.580872371656, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.7278681808419, 380.18829798380466, 395.98812035638923, 406.4817357868717, 419.37513105472834, 11796.646100616985, 12009.759804803909, 12805.0, 16707.66584668309, 17760.555986100353, 17672.734908976345, 18493.0, 18543.16555754991, 18779.154938027787, 18838.71741923872, 5104.234364845275, 5372.54333381663, 5375.462453754612, 5385.3268341457315, 5387.380451248117, 5120.795168193268, 5258.973705131469, 5282.471752824726, 5304.071787437202, 5378.085725426687, 5258.342707161605, 5264.973675131619, 5267.3679158500845, 5268.736563171845, 5272.209168624718, 5091.159958606825, 5242.344706911636, 5263.526282634562, 5267.789288428477, 5289.550388217006, 4778.04439112179, 4778.474367819537, 4781.306710526989, 4782.665213435061, 4788.0663270662235, 20824.16703331865, 21757.891210544003, 21813.0, 21977.560448791093, 22083.0, 2134.679798030292, 2189.638709612912, 2190.715207023077, 2218.057325636614, 2228.253535065755, 7246.637668116573, 12690.42893069814, 13023.544175953892, 13388.0, 13633.250171240577, 1660.3607611069704, 1717.0, 1734.9826501735074, 1793.99536259695, 1821.799602043774, 62781.6860915697, 71256.0, 73150.63424682949, 73203.63398182976, 74623.0, 10384.119463851977, 10584.465252538339, 10642.946785266178, 10731.302465339728, 10856.402897840695, 15289.0, 15303.846961530242, 15304.923475382551, 15309.6172595686, 15314.693706126038, 6810.62541560219, 6955.6869940852785, 7030.543014704028, 7110.608916509642, 7187.389071928945, 6292.622442653455, 6300.464460520907, 6412.67936603168, 6422.632609825364, 6482.189726284215, 4376.496702879146, 4546.954530454719, 4623.375844261049, 4637.25803871379, 4785.880352991203, 1847.3349594146096, 1852.19429548147, 1852.3887117251275, 1866.169554548213, 1940.7768106667631, 771.3751860992614, 790.7509134622576, 792.9920700792918, 795.6936579416966, 795.9880601790978, 16430.75353869693, 17539.615096225945, 17580.736288955675, 17921.0, 17927.402333638864, 787.6534324897027, 802.0936341933694, 802.1377019703842, 815.5351449673748, 816.1960468938091, 9423.057694230522, 9665.420074795395, 9876.851847222297, 9925.50372481373, 10092.04125608066, 426029.0, 434203.0, 435750.0, 451613.0, 452979.0, 82979.17020829835, 83143.5842820782, 85064.0, 85291.0, 85718.0, 822.6421506644629, 832.9167083291622, 841.8863453433707, 842.5787106446777, 843.409613270713, 13787.448502059806, 14077.436902524194, 14216.21810800416, 14325.928370358499, 14778.704425911637, 38275.80862095672, 38859.611403885596, 39256.607433925295, 39937.0, 39951.400728988505, 49798.75100624474, 50102.0, 50200.24699629436, 50656.240156398424, 50667.0, 7246.347828695442, 7547.132406875191, 7562.0, 7672.079350477755, 7865.488743231672, 10837.729056773644, 11275.830862537232, 11378.487968041623, 11619.350941738388, 11753.059755219732, 9129.817403652023, 9385.953070234606, 9665.420074795535, 9832.754181145528, 9926.15627671656, 9481.431114133175, 9769.511524423893, 9862.358946668444, 9878.061584149495, 10168.135708464863, 9520.285978551776, 9680.177184939359, 9707.563159657691, 9948.651797187063, 9978.950105249427, 7388.4828062037295, 7538.924610753822, 7545.094588649177, 7586.886196706943, 7712.074551053909, 7176.7846964591145, 9417.858732118886, 9429.62281508732, 9459.95270023673, 9737.318387712792], "value": 8223.180808016466, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1021.8518314844353, 1108.5898217659467, 1122.9663110106699, 1129.1418521923338, 1182.0130191290273, 6328.430441260286, 7339.0, 7562.168161502231, 7563.999999999993, 7573.356264717506, 7598.39212862971, 7645.853122031698, 7651.617419129036, 7659.463837531375, 7676.92323076769, 1727.6890159771235, 2085.9895700521474, 2121.9469513262216, 2123.968140477894, 2124.2140408048967, 1771.3357490940898, 2065.834733221346, 2118.8093071623553, 2123.2462475821094, 2135.7010018597407, 2069.3584988653565, 2071.9482012949647, 2072.6476498995207, 2078.864873783207, 2079.1683326669304, 2036.3992622176493, 2061.701053347271, 2064.267185149273, 2072.4404410809148, 2072.6476498995207, 2019.424464027745, 2056.311135769519, 2057.9279725209694, 2067.58648270345, 2109.8839563823985, 7354.338109570111, 7406.111266647983, 7409.110906691231, 7415.221401752792, 7424.294692004251, 4364.54172311909, 4484.439445069366, 4499.03270796777, 4503.482099558527, 4510.233260345749, 2668.9065882694013, 2877.079334612925, 2928.311846716041, 2932.5601159825983, 3009.9849500752357, 1079.1474734959372, 1134.2740645986576, 1158.1661203933154, 1169.7601991591703, 1211.684961909901, 10293.845592316122, 10327.535260913202, 10351.79296414075, 10356.948215258952, 10365.533550990223, 3876.9418458723144, 4148.564400737879, 4174.08170202555, 4246.893827654333, 4253.128108737763, 8798.956005220061, 8815.0, 8816.338774591932, 8817.603207855662, 8824.294056475466, 4558.088382323548, 4560.840370587013, 4561.087782443458, 4561.566651168134, 4565.977170114194, 3404.9659503405146, 3775.414810704345, 3915.8433662653742, 3922.4508568800393, 3925.2542017017245, 3511.9297614047578, 3542.5040494330815, 3639.1993761372455, 3667.211549516867, 3679.466477360768, 1226.3193927370216, 1241.0, 1244.7821631214501, 1254.0907841814794, 1261.6215135459327, 778.381186956371, 783.9529628222211, 795.1412474527497, 798.7603718884311, 802.9759107226793, 4534.433195850519, 4635.466921304093, 4743.02767932573, 4785.1625965455905, 4900.534449227318, 494.2043310270462, 500.384527031753, 505.2925903734727, 506.4960364437408, 507.520393228403, 3079.5329807404273, 3132.2482604174834, 3147.8111313321274, 3193.760467964912, 3237.8704851806133, 8898.555072246361, 8987.41581797181, 9061.0, 9123.0, 9124.087591240823, 9020.503872287087, 9072.773180670403, 9122.0, 9142.268618510503, 9149.405288656217, 6929.89605155913, 7089.432845372357, 7153.570785752871, 7155.141383034069, 7157.176924653628, 4299.419578357007, 4335.566443355639, 4373.540778218368, 4401.163778882003, 4477.641788656965, 8049.436539442417, 8130.268275855202, 8302.16978302189, 8369.581520923808, 8376.874346884924, 8329.3336533076, 8412.527807633518, 8564.05795362522, 8574.22831945128, 8585.141485851365, 5604.579656525695, 6287.528435367274, 6406.935930640634, 6409.038644203407, 6411.230652321751, 6291.528135389955, 6366.9681651591445, 6832.93167068323, 7220.097487814023, 7273.781786546412, 5866.120081987736, 5893.204417403767, 5921.733521991435, 5923.378045305353, 5930.199423077829, 6040.36576159497, 6092.055731361646, 6093.9390606093375, 6104.511639068953, 6107.6640784757265, 5624.325080990144, 5645.463680950304, 5677.886442271214, 5699.145128230799, 5701.0, 3963.781991990468, 3964.5044369453817, 4060.878173654795, 4071.1043570414463, 4167.562405947332, 4188.246115699081, 4226.598473145046, 4234.640055595309, 4283.5288118306335, 4316.266234740039], "value": 3846.607455900367, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.7696743182068, 351.7063252184426, 351.8856371679205, 352.13726370392544, 360.19676122247387, 4755.619550435966, 4779.402574678165, 4801.279808028793, 4824.831130910419, 4848.927266091011, 4738.147133515966, 4742.288656701497, 4762.952370476294, 4768.54698803614, 4778.139934811733, 3591.730620203495, 3715.7956312402807, 3738.0467980664894, 3738.4205448155444, 3855.8457661693496, 3718.4980027696183, 3721.888343349704, 3728.3661777497755, 3734.1971476132626, 3735.3463143949834, 3687.542437192956, 3696.168362118529, 3698.389765688658, 3705.3515634763944, 3734.3091528067257, 3613.6747692707518, 3715.2198038411966, 3732.234891847165, 3738.5700644425965, 3744.7004239660896, 3209.1816586770456, 3213.405519978788, 3233.466478031122, 3248.31785325082, 3249.301400198945, 4616.492185859553, 4623.583877451012, 4628.95371046292, 4631.143238500888, 4674.275487299474, 4201.68487363449, 4202.390653355277, 4213.30480470722, 4226.154769046203, 4227.217964676544, 4732.360998001063, 4964.851054468371, 4972.378452693413, 4973.3286006389035, 4980.277859710358, 3482.4428091505238, 3532.0, 3539.911502212465, 3544.5290204565076, 3561.857525698969, 4953.479884612136, 5197.974010129963, 5249.0, 5249.763760630781, 5253.579713622901, 3455.2225749206227, 3486.965130348715, 3553.982230088833, 3592.2815436912724, 3618.4753210784816, 3610.4223324268514, 3628.582712987987, 3638.599754027055, 3642.8542858285373, 3654.269146170723, 4194.895127621773, 4201.285781417167, 4207.768572728468, 4213.4410268200345, 4251.213525497793, 4691.81232750693, 4819.518048195223, 4923.827666031743, 4961.578265847444, 4968.8757781055765, 1214.160546771624, 1218.6959952850366, 1219.7560487902456, 1222.3826967381415, 1222.5329604474666, 777.5606782168057, 815.1318845429598, 815.7348861619997, 815.8857759913617, 825.591332290508, 4202.054537729047, 4204.1801848639225, 4219.936700949488, 4224.0, 4232.814811852686, 4985.0, 4985.775640096204, 4993.575546078551, 5002.399712034579, 5003.574696150868, 4238.343056826136, 4241.597048280409, 4248.9787551062045, 4249.2563801334645, 4255.829766809355, 2528.633348164506, 2613.2421597736443, 2634.170236375574, 2644.2331723799884, 2645.4444566641223, 4621.4223222097235, 4629.629629629621, 4650.348951146842, 4660.347551342815, 4696.577308042292, 4977.975110124498, 5012.749362531858, 5013.949860501421, 5042.722650254198, 5059.367579052619, 4340.392345071693, 4611.884702882455, 4635.768211589407, 4641.2109941310055, 4657.883552911068, 4934.383202099738, 4947.307376967299, 4947.82682606107, 4952.059108769322, 5018.082279366315, 4896.510348965075, 4937.530934561211, 4940.456549779449, 4941.43173535048, 4947.505249475023, 4534.0, 4824.372831531879, 4853.563179313879, 4875.293082503088, 4877.414710234794, 3423.657634236656, 3508.9298214035066, 3514.121469632592, 3527.171114788074, 3531.9823400882833, 4977.875553111201, 5051.166557517969, 5089.694618322765, 5172.1984341508205, 5176.256584010481, 5009.749512524432, 5023.673461225155, 5034.597232221487, 5040.294358789845, 5060.671056381323, 4895.877603059952, 4991.57571606403, 5012.8496145115705, 5015.84952451427, 5019.222020586815, 4908.680935739166, 4919.704817710949, 4920.5571498565305, 4927.655064145475, 4927.852164435073, 5038.992201559629, 5040.470750571284, 5053.393592768891, 5058.822941197041, 5062.468440813662, 4677.3632705776345, 4869.366982292279, 4897.2654101885, 4907.754612269444, 4919.926201106915], "value": 3724.507088712184, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.8761672432064, 567.3305499510578, 689.9306075582846, 703.5145749432892, 718.0808565036754, 11809.822852657206, 13046.739065218713, 13057.67355816104, 13059.673508162301, 13077.738445231089, 11117.499712512936, 12606.117571769957, 12697.80953285699, 12721.42753576089, 12733.617991460247, 794.031281836159, 808.8827120067587, 828.2959484438239, 835.8955130608674, 835.9414840961135, 822.4078663362369, 834.2700137379793, 836.1638361638362, 836.7615229659548, 836.8912041434605, 873.4933738431727, 875.4965894610592, 875.8861348024748, 876.1851478125341, 876.2289185516736, 867.7483529776357, 867.9783005424883, 868.7437206024238, 869.2958703450225, 869.6086760957594, 631.3497097989078, 635.4598591197491, 637.2703254773259, 638.856257342099, 640.1422094393535, 13599.116057456235, 13761.449542018307, 13780.65548361299, 13894.513692020731, 13937.930310348384, 11700.648980530512, 12601.597568656503, 13027.934860325733, 13299.0, 13448.394822233024, 9646.276529260334, 9719.757006074904, 9821.508924553744, 9857.309988300822, 10335.844962325571, 2790.4000639862325, 2813.071686343502, 2891.768658507335, 2923.093840909325, 2924.1958461423064, 62164.68917655428, 62204.0, 62786.68606656983, 63399.68300158516, 65331.67334163299, 10295.691129265984, 10352.5341359639, 10410.0, 10454.268201225841, 10846.737992847404, 19469.610607787767, 19520.902395488214, 19523.414297571097, 19556.0, 19684.409467715992, 6070.696465176724, 6090.512758979271, 6129.957907155795, 6198.845028874224, 6337.461315788118, 3273.3126043530647, 3276.1973316537124, 3308.6856748608843, 3335.3996280669226, 3354.8322583871195, 2709.67483901933, 2758.641376621026, 2775.777937764974, 2785.4846853332197, 2788.7071857454675, 449.2250867254026, 461.3011287898836, 468.26482422596195, 480.9831655892097, 507.0, 530.2761730238274, 541.8672425255759, 558.6201383059478, 563.3549585724322, 563.605476166685, 8121.269085782308, 8137.0, 8333.374664596873, 8628.1337047354, 8639.395242333103, 593.7743657410157, 607.1984979826586, 621.6083867163749, 623.329920335632, 625.9812205633838, 4079.1637714268504, 4208.431861698693, 4242.236397448427, 4291.721038132511, 4316.6608047793325, 50252.24621630678, 60830.69584652137, 61027.38972610217, 62623.06065409022, 62710.0, 42923.78538107289, 48337.75831120822, 52099.218511722356, 53423.732881335345, 53596.464035359924, 48005.25365293058, 48553.75723121362, 48689.26966095441, 48909.75545122252, 49888.50111498839, 7870.527768333689, 8171.632276547689, 8231.218034286732, 8368.87446688288, 8462.788430289293, 32814.34371312608, 33407.66592334046, 33457.66542334643, 34379.48430773491, 34545.82727086348, 39692.80153599329, 42633.147337053706, 43030.0, 43876.341854872844, 44096.0, 8864.246539044254, 8948.507832069039, 9243.352965292364, 9264.212541934012, 9352.625894964125, 11096.223264371416, 11145.052670522771, 11254.831177532507, 11382.0, 11576.652700419001, 10240.487975601041, 10691.732706682395, 10861.076808471369, 11040.392778397265, 11059.391733454737, 9720.204573043424, 9780.510974451392, 9937.20502359795, 10118.645847395306, 10119.696409107739, 8551.74344769658, 8817.911820881965, 8975.0, 9197.448153110834, 9209.033051529494, 8132.87800683002, 8365.790855228668, 8576.614052367784, 8578.227959483676, 8843.734687959372, 7273.599952002691, 7356.583770930432, 7730.226977302224, 7812.617151768765, 8304.211099945496], "value": 5956.335179053237, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1576.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 118.0, 116.0, 119.0, 116.0, 114.0, 117.0, 115.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 127.0, 127.0, 167.0, 128.0, 136.0, 134.0, 126.0, 155.0, 128.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 163.0, 163.0, 162.0, 163.0, 162.0, 163.0, 163.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 116.0, 78.0, 77.0, 77.0, 76.0, 92.0, 83.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 112.0, 85.0, 85.0, 85.0, 108.0, 85.0, 84.0, 116.0], "value": 93.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 115.0, 113.0, 113.0, 114.0, 114.0, 114.0, 113.0, 116.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 151.0, 150.0, 149.0, 149.0, 150.0, 149.0, 149.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 61.0, 62.0, 63.0, 63.0, 62.0, 63.0, 63.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 67.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 88.0, 88.0, 89.0, 91.0, 89.0, 89.0, 89.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 148.0, 138.0, 150.0, 138.0, 146.0, 146.0, 177.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 189.0, 187.0, 196.0, 190.0, 188.0, 195.0, 185.0, 184.0, 184.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 76.0, 76.0, 70.0, 71.0, 69.0, 70.0, 71.0, 70.0], "value": 71.3, "unit": "score"}]}]} 08:28:29 INFO - Return code: 0 08:28:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:28:29 ERROR - # TBPL SUCCESS # 08:28:29 INFO - Running post-action listener: _resource_record_post_action 08:28:29 INFO - [mozharness: 2017-02-20 16:28:29.850096Z] Finished run-tests step (success) 08:28:29 INFO - Running post-run listener: _resource_record_post_run 08:28:29 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 353574912; Read time: 16; Write time: 304176 08:28:29 INFO - TinderboxPrint: CPU usage
13.1% 08:28:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:28:29 INFO - TinderboxPrint: I/O write bytes / time
353,574,912 / 304,176 08:28:29 INFO - TinderboxPrint: CPU idle
8,353.7 (86.8%) 08:28:29 INFO - TinderboxPrint: CPU user
1,217.1 (12.7%) 08:28:29 INFO - TinderboxPrint: Swap in / out
0 / 0 08:28:29 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 104869888; Read time: 0; Write time: 86364 08:28:29 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248705024; Read time: 16; Write time: 217812 08:28:30 INFO - Running post-run listener: _upload_blobber_files 08:28:30 INFO - Blob upload gear active. 08:28:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:28:30 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 08:28:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:28:30 INFO - (blobuploader) - INFO - Open directory for files ... 08:28:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:28:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:28:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:31 INFO - (blobuploader) - INFO - Done attempting. 08:28:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:28:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:28:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:32 INFO - (blobuploader) - INFO - Done attempting. 08:28:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:28:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:28:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:34 INFO - (blobuploader) - INFO - Done attempting. 08:28:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:28:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:28:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:28:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:28:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:28:37 INFO - (blobuploader) - INFO - Done attempting. 08:28:37 INFO - (blobuploader) - INFO - Iteration through files over. 08:28:37 INFO - Return code: 0 08:28:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:28:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:28:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bfe8ad46a5704999e5c65ba8d6d8ead1a8f6a76b7bc40d1153428c476e6b8fd6d29fc9e5c0236e416d467d23508b8ff83fcf257a82d2ca92c644a9a2cae287c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b87381449baa4177fb5d0a23cf072e830b7744c24817221d5819aec0fb288a20ae061f28e7ea3b983f54f1f7b3cf8dc75b4bc6156e8fc430b02019b21c68478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c7df09b87301714237030cac29c9ec6a2c2647430d7af3780676590a00705e4534ee86fbc3325c92f4b145daec13aab76c92844dd4d678df5e42365b56464be", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8c454d49f52739db3e07ae44d0c049a22b65d9ca442367cc09892710034b49212cbe3b32670be3c1bc31e60e0dffd0c29451a3896169ce80e42fba5ca60968d"} 08:28:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:28:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:28:37 INFO - Contents: 08:28:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bfe8ad46a5704999e5c65ba8d6d8ead1a8f6a76b7bc40d1153428c476e6b8fd6d29fc9e5c0236e416d467d23508b8ff83fcf257a82d2ca92c644a9a2cae287c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b87381449baa4177fb5d0a23cf072e830b7744c24817221d5819aec0fb288a20ae061f28e7ea3b983f54f1f7b3cf8dc75b4bc6156e8fc430b02019b21c68478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c7df09b87301714237030cac29c9ec6a2c2647430d7af3780676590a00705e4534ee86fbc3325c92f4b145daec13aab76c92844dd4d678df5e42365b56464be", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8c454d49f52739db3e07ae44d0c049a22b65d9ca442367cc09892710034b49212cbe3b32670be3c1bc31e60e0dffd0c29451a3896169ce80e42fba5ca60968d"} 08:28:37 INFO - Running post-run listener: copy_logs_to_upload_dir 08:28:37 INFO - Copying logs to upload dir... 08:28:37 INFO - mkdir: /builds/slave/test/build/upload/logs 08:28:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.626188 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-20 08:28:37.900634) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 08:28:37.904268) ========= 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=17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bfe8ad46a5704999e5c65ba8d6d8ead1a8f6a76b7bc40d1153428c476e6b8fd6d29fc9e5c0236e416d467d23508b8ff83fcf257a82d2ca92c644a9a2cae287c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b87381449baa4177fb5d0a23cf072e830b7744c24817221d5819aec0fb288a20ae061f28e7ea3b983f54f1f7b3cf8dc75b4bc6156e8fc430b02019b21c68478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c7df09b87301714237030cac29c9ec6a2c2647430d7af3780676590a00705e4534ee86fbc3325c92f4b145daec13aab76c92844dd4d678df5e42365b56464be", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8c454d49f52739db3e07ae44d0c049a22b65d9ca442367cc09892710034b49212cbe3b32670be3c1bc31e60e0dffd0c29451a3896169ce80e42fba5ca60968d"} build_url:https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011417 build_url: 'https://queue.taskcluster.net/v1/task/A5aj3my4QS-xYc-RUvXGMw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bfe8ad46a5704999e5c65ba8d6d8ead1a8f6a76b7bc40d1153428c476e6b8fd6d29fc9e5c0236e416d467d23508b8ff83fcf257a82d2ca92c644a9a2cae287c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1b87381449baa4177fb5d0a23cf072e830b7744c24817221d5819aec0fb288a20ae061f28e7ea3b983f54f1f7b3cf8dc75b4bc6156e8fc430b02019b21c68478", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c7df09b87301714237030cac29c9ec6a2c2647430d7af3780676590a00705e4534ee86fbc3325c92f4b145daec13aab76c92844dd4d678df5e42365b56464be", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f8c454d49f52739db3e07ae44d0c049a22b65d9ca442367cc09892710034b49212cbe3b32670be3c1bc31e60e0dffd0c29451a3896169ce80e42fba5ca60968d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 08:28:37.928977) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:28:37.929300) ========= 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=17051fcd5208b27b50bedfeb000001dd-1487606753.601667-1816491329 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 08:28:38.013310) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 08:28:38.013627) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 08:28:38.013921) ========= ========= Total master_lag: 0.18 =========