builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-010 starttime: 1487346172.41 results: success (0) revision: df9a0acc26486790c8794b787c764654d32c3f9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:52.411312) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:52.411815) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:52.412145) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:52.426621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:52.426990) ========= 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=eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 07:42:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 29.8M=0s 2017-02-17 07:42:52 (29.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.388624 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:52.829294) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:52.829811) ========= 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=eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019789 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:52.878838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 07:42:52.879203) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev df9a0acc26486790c8794b787c764654d32c3f9c --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 df9a0acc26486790c8794b787c764654d32c3f9c --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=eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 07:42:52,988 truncating revision to first 12 chars 2017-02-17 07:42:52,988 Setting DEBUG logging. 2017-02-17 07:42:52,988 attempt 1/10 2017-02-17 07:42:52,988 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/df9a0acc2648?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 07:42:54,265 unpacking tar archive at: mozilla-central-df9a0acc2648/testing/mozharness/ program finished with exit code 0 elapsedTime=1.581317 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 07:42:54.473252) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:54.473578) ========= script_repo_revision: df9a0acc26486790c8794b787c764654d32c3f9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:54.473962) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:54.474266) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 07:42:54.482494) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-17 07:42:54.482869) ========= /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=eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:42:54 INFO - MultiFileLogger online at 20170217 07:42:54 in /builds/slave/test 07:42:54 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 07:42:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:42:54 INFO - {'append_to_log': False, 07:42:54 INFO - 'base_work_dir': '/builds/slave/test', 07:42:54 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:42:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:42:54 INFO - 'branch': 'Firefox-Non-PGO', 07:42:54 INFO - 'buildbot_json_path': 'buildprops.json', 07:42:54 INFO - 'config_files': ('talos/linux_config.py',), 07:42:54 INFO - 'default_actions': ('clobber', 07:42:54 INFO - 'read-buildbot-config', 07:42:54 INFO - 'download-and-extract', 07:42:54 INFO - 'populate-webroot', 07:42:54 INFO - 'create-virtualenv', 07:42:54 INFO - 'install', 07:42:54 INFO - 'run-tests'), 07:42:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:42:54 INFO - 'download_minidump_stackwalk': True, 07:42:54 INFO - 'download_symbols': 'ondemand', 07:42:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:42:54 INFO - 'tooltool.py': '/tools/tooltool.py', 07:42:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:42:54 INFO - '/tools/misc-python/virtualenv.py')}, 07:42:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:42:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:42:54 INFO - 'installer_path': 'installer.exe', 07:42:54 INFO - 'log_level': 'info', 07:42:54 INFO - 'log_name': 'talos', 07:42:54 INFO - 'log_to_console': True, 07:42:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:42:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:42:54 INFO - 'opt_config_files': (), 07:42:54 INFO - 'pip_index': False, 07:42:54 INFO - 'sps_profile': False, 07:42:54 INFO - 'sps_profile_interval': 0, 07:42:54 INFO - 'suite': 'dromaeojs', 07:42:54 INFO - 'system_bits': '32', 07:42:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:42:54 INFO - 'title': 'talos-linux64-ix-010', 07:42:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:42:54 INFO - 'use_talos_json': True, 07:42:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:42:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:42:54 INFO - 'work_dir': 'build'} 07:42:54 INFO - [mozharness: 2017-02-17 15:42:54.695008Z] Running clobber step. 07:42:54 INFO - Running pre-action listener: _resource_record_pre_action 07:42:54 INFO - Running main action method: clobber 07:42:54 INFO - rmtree: /builds/slave/test/build 07:42:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:42:55 INFO - Running post-action listener: _resource_record_post_action 07:42:55 INFO - [mozharness: 2017-02-17 15:42:55.602973Z] Finished clobber step (success) 07:42:55 INFO - [mozharness: 2017-02-17 15:42:55.603073Z] Running read-buildbot-config step. 07:42:55 INFO - Running pre-action listener: _resource_record_pre_action 07:42:55 INFO - Running main action method: read_buildbot_config 07:42:55 INFO - Using buildbot properties: 07:42:55 INFO - { 07:42:55 INFO - "project": "", 07:42:55 INFO - "product": "firefox", 07:42:55 INFO - "who": "ffxbld@noreply.mozilla.org", 07:42:55 INFO - "installer_path": "public/build/target.tar.bz2", 07:42:55 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:42:55 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:42:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 07:42:55 INFO - "stage_platform": "linux64", 07:42:55 INFO - "basedir": "/builds/slave/test", 07:42:55 INFO - "buildnumber": 834, 07:42:55 INFO - "platform": "ubuntu64_hw", 07:42:55 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:42:55 INFO - "slavebuilddir": "test", 07:42:55 INFO - "taskId": "G3Ggszg4QGmncAWO_T3uWQ", 07:42:55 INFO - "branch": "mozilla-central", 07:42:55 INFO - "script_repo_revision": "production", 07:42:55 INFO - "revision": "df9a0acc26486790c8794b787c764654d32c3f9c", 07:42:55 INFO - "slavename": "talos-linux64-ix-010", 07:42:55 INFO - "repo_path": "mozilla-central" 07:42:55 INFO - } 07:42:55 INFO - Finding installer, test and symbols from parent task. 07:42:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3Ggszg4QGmncAWO_T3uWQ'}, attempt #1 07:42:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3Ggszg4QGmncAWO_T3uWQ'}, attempt #1 07:42:56 INFO - Task dependencies: P482Qw7lQhC0z1zIaZppAg 07:42:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg'}, attempt #1 07:42:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 07:42:57 INFO - Set test_url: https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/test_packages.json 07:42:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.crashreporter-symbols.zip 07:42:57 INFO - Running post-action listener: _resource_record_post_action 07:42:57 INFO - [mozharness: 2017-02-17 15:42:57.020508Z] Finished read-buildbot-config step (success) 07:42:57 INFO - [mozharness: 2017-02-17 15:42:57.020725Z] Running download-and-extract step. 07:42:57 INFO - Running pre-action listener: _resource_record_pre_action 07:42:57 INFO - Running main action method: download_and_extract 07:42:57 INFO - mkdir: /builds/slave/test/build/tests 07:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:57 INFO - trying https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.test_packages.json 07:42:57 INFO - Downloading https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:42:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:42:57 INFO - Downloaded 896 bytes. 07:42:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:42:57 INFO - Using the following test package requirements: 07:42:57 INFO - {u'common': [u'target.common.tests.zip'], 07:42:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:42:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:42:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:42:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:42:57 INFO - u'mozbase': [u'target.common.tests.zip'], 07:42:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:42:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:42:57 INFO - u'web-platform': [u'target.common.tests.zip', 07:42:57 INFO - u'target.web-platform.tests.zip'], 07:42:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:42:57 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:42:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.common.tests.zip 07:42:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:42:57 INFO - Fetch https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.common.tests.zip into memory 07:42:58 INFO - Expected file size: 57027601 07:42:58 INFO - Obtained file size: 57027601 07:43:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:43:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.common.tests.zip 07:43:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:43:08 INFO - Fetch https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.common.tests.zip into memory 07:43:09 INFO - Expected file size: 57027601 07:43:09 INFO - Obtained file size: 57027601 07:43:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.talos.tests.zip 07:43:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:43:20 INFO - Fetch https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.talos.tests.zip into memory 07:43:21 INFO - Expected file size: 14403647 07:43:21 INFO - Obtained file size: 14403647 07:43:21 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')]} 07:43:21 INFO - trying https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 07:43:21 INFO - Downloading https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:43:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:43:22 INFO - Downloaded 61398070 bytes. 07:43:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 07:43:22 INFO - mkdir: /builds/slave/test/properties 07:43:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:43:22 INFO - Writing to file /builds/slave/test/properties/build_url 07:43:22 INFO - Contents: 07:43:22 INFO - build_url:https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 07:43:22 INFO - Running post-action listener: _resource_record_post_action 07:43:22 INFO - Running post-action listener: set_extra_try_arguments 07:43:22 INFO - [mozharness: 2017-02-17 15:43:22.596249Z] Finished download-and-extract step (success) 07:43:22 INFO - [mozharness: 2017-02-17 15:43:22.596345Z] Running populate-webroot step. 07:43:22 INFO - Running pre-action listener: _resource_record_pre_action 07:43:22 INFO - Running main action method: populate_webroot 07:43:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:43:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:43:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:43:22 INFO - '--tppagecycles', 07:43:22 INFO - '7'], 07:43:22 INFO - 'tests': ['tsvgm']}}, 07:43:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:43:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:43:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:43:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:43:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:43:22 INFO - 'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:43:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:43:22 INFO - 'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['damp', 'tps']}, 07:43:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:43:22 INFO - 'tests': ['damp', 'tps']}, 07:43:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['dromaeo_dom']}, 07:43:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:43:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['basic_compositor_video']}, 07:43:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:43:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['a11yr', 07:43:22 INFO - 'ts_paint', 07:43:22 INFO - 'tpaint', 07:43:22 INFO - 'sessionrestore', 07:43:22 INFO - 'sessionrestore_no_auto_restore', 07:43:22 INFO - 'tabpaint']}, 07:43:22 INFO - 'other-e10s': {'tests': ['a11yr', 07:43:22 INFO - 'ts_paint', 07:43:22 INFO - 'tpaint', 07:43:22 INFO - 'sessionrestore', 07:43:22 INFO - 'sessionrestore_no_auto_restore', 07:43:22 INFO - 'tabpaint']}, 07:43:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:43:22 INFO - 'ts_paint', 07:43:22 INFO - 'tpaint', 07:43:22 INFO - 'sessionrestore', 07:43:22 INFO - 'sessionrestore_no_auto_restore', 07:43:22 INFO - 'tabpaint']}, 07:43:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:43:22 INFO - 'ts_paint', 07:43:22 INFO - 'tpaint', 07:43:22 INFO - 'sessionrestore', 07:43:22 INFO - 'sessionrestore_no_auto_restore', 07:43:22 INFO - 'tabpaint']}, 07:43:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['a11yr', 07:43:22 INFO - 'ts_paint', 07:43:22 INFO - 'tpaint', 07:43:22 INFO - 'sessionrestore', 07:43:22 INFO - 'sessionrestore_no_auto_restore', 07:43:22 INFO - 'tabpaint']}, 07:43:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['a11yr', 07:43:22 INFO - 'ts_paint', 07:43:22 INFO - 'tpaint', 07:43:22 INFO - 'sessionrestore', 07:43:22 INFO - 'sessionrestore_no_auto_restore', 07:43:22 INFO - 'tabpaint']}, 07:43:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['tsvgx', 07:43:22 INFO - 'tsvgr_opacity', 07:43:22 INFO - 'tart', 07:43:22 INFO - 'tscrollx', 07:43:22 INFO - 'cart', 07:43:22 INFO - 'tsvg_static']}, 07:43:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:43:22 INFO - 'tsvgr_opacity', 07:43:22 INFO - 'tart', 07:43:22 INFO - 'tscrollx', 07:43:22 INFO - 'cart', 07:43:22 INFO - 'tsvg_static']}, 07:43:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:43:22 INFO - 'talos_options': ['--disable-e10s'], 07:43:22 INFO - 'tests': ['tp5o']}, 07:43:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:43:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:43:22 INFO - 'talos_options': ['--disable-e10s', 07:43:22 INFO - '--xperf_path', 07:43:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:22 INFO - 'tests': ['tp5n']}, 07:43:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:43:22 INFO - 'talos_options': ['--xperf_path', 07:43:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:22 INFO - 'tests': ['tp5n']}}, 07:43:22 INFO - 'talos.zip': {'path': '', 07:43:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:43:22 INFO - Running post-action listener: _resource_record_post_action 07:43:22 INFO - [mozharness: 2017-02-17 15:43:22.609423Z] Finished populate-webroot step (success) 07:43:22 INFO - [mozharness: 2017-02-17 15:43:22.609512Z] Running create-virtualenv step. 07:43:22 INFO - Running pre-action listener: _resource_record_pre_action 07:43:22 INFO - Running main action method: create_virtualenv 07:43:22 INFO - Creating virtualenv /builds/slave/test/build/venv 07:43:22 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 07:43:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:43:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:43:22 INFO - Using real prefix '/usr' 07:43:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:43:23 INFO - Installing distribute.............................................................................................................................................................................................done. 07:43:25 INFO - Installing pip.................done. 07:43:25 INFO - Return code: 0 07:43:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:43:25 INFO - Reading from file tmpfile_stdout 07:43:25 INFO - Output received: 07:43:25 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) 07:43:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:43:25 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')]} 07:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:25 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')]} 07:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:25 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:25 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 07:43:25 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 07:43:25 INFO - Using env: {'DISPLAY': ':0', 07:43:25 INFO - 'HOME': '/home/cltbld', 07:43:25 INFO - 'LANG': 'en_US.UTF-8', 07:43:25 INFO - 'LANGUAGE': 'en_US:en', 07:43:25 INFO - 'LOGNAME': 'cltbld', 07:43:25 INFO - 'MAIL': '/var/mail/cltbld', 07:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:25 INFO - 'MOZ_NO_REMOTE': '1', 07:43:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:25 INFO - 'NO_EM_RESTART': '1', 07:43:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:25 INFO - 'PWD': '/builds/slave/test', 07:43:25 INFO - 'SHELL': '/bin/bash', 07:43:25 INFO - 'SHLVL': '1', 07:43:25 INFO - 'TERM': 'linux', 07:43:25 INFO - 'TMOUT': '86400', 07:43:25 INFO - 'USER': 'cltbld', 07:43:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:25 INFO - '_': '/tools/buildbot/bin/python'} 07:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:25 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 07:43:25 INFO - Cleaning up... 07:43:25 INFO - Return code: 0 07:43:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:43:25 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')]} 07:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:25 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')]} 07:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:25 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:25 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 07:43:25 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 07:43:25 INFO - Using env: {'DISPLAY': ':0', 07:43:25 INFO - 'HOME': '/home/cltbld', 07:43:25 INFO - 'LANG': 'en_US.UTF-8', 07:43:25 INFO - 'LANGUAGE': 'en_US:en', 07:43:25 INFO - 'LOGNAME': 'cltbld', 07:43:25 INFO - 'MAIL': '/var/mail/cltbld', 07:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:25 INFO - 'MOZ_NO_REMOTE': '1', 07:43:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:25 INFO - 'NO_EM_RESTART': '1', 07:43:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:25 INFO - 'PWD': '/builds/slave/test', 07:43:25 INFO - 'SHELL': '/bin/bash', 07:43:25 INFO - 'SHLVL': '1', 07:43:25 INFO - 'TERM': 'linux', 07:43:25 INFO - 'TMOUT': '86400', 07:43:25 INFO - 'USER': 'cltbld', 07:43:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:25 INFO - '_': '/tools/buildbot/bin/python'} 07:43:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:25 INFO - Downloading/unpacking psutil>=3.1.1 07:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:43:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:27 INFO - Installing collected packages: psutil 07:43:27 INFO - Running setup.py install for psutil 07:43:28 INFO - building 'psutil._psutil_linux' extension 07:43:28 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 07:43:28 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 07:43:28 INFO - building 'psutil._psutil_posix' extension 07:43:28 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 07:43:28 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 07:43:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:28 INFO - Successfully installed psutil 07:43:28 INFO - Cleaning up... 07:43:28 INFO - Return code: 0 07:43:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:28 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 07:43:28 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 07:43:28 INFO - Using env: {'DISPLAY': ':0', 07:43:28 INFO - 'HOME': '/home/cltbld', 07:43:28 INFO - 'LANG': 'en_US.UTF-8', 07:43:28 INFO - 'LANGUAGE': 'en_US:en', 07:43:28 INFO - 'LOGNAME': 'cltbld', 07:43:28 INFO - 'MAIL': '/var/mail/cltbld', 07:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:28 INFO - 'MOZ_NO_REMOTE': '1', 07:43:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:28 INFO - 'NO_EM_RESTART': '1', 07:43:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:28 INFO - 'PWD': '/builds/slave/test', 07:43:28 INFO - 'SHELL': '/bin/bash', 07:43:28 INFO - 'SHLVL': '1', 07:43:28 INFO - 'TERM': 'linux', 07:43:28 INFO - 'TMOUT': '86400', 07:43:28 INFO - 'USER': 'cltbld', 07:43:28 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:28 INFO - '_': '/tools/buildbot/bin/python'} 07:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:43:28 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 07:43:28 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 07:43:28 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 07:43:28 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 07:43:28 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 07:43:28 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 07:43:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:43:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:43:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:43:30 INFO - Installing collected packages: mozsystemmonitor 07:43:30 INFO - Running setup.py install for mozsystemmonitor 07:43:30 INFO - Successfully installed mozsystemmonitor 07:43:30 INFO - Cleaning up... 07:43:30 INFO - Return code: 0 07:43:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:43:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:30 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:30 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 07:43:30 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 07:43:30 INFO - Using env: {'DISPLAY': ':0', 07:43:30 INFO - 'HOME': '/home/cltbld', 07:43:30 INFO - 'LANG': 'en_US.UTF-8', 07:43:30 INFO - 'LANGUAGE': 'en_US:en', 07:43:30 INFO - 'LOGNAME': 'cltbld', 07:43:30 INFO - 'MAIL': '/var/mail/cltbld', 07:43:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:30 INFO - 'MOZ_NO_REMOTE': '1', 07:43:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:30 INFO - 'NO_EM_RESTART': '1', 07:43:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:30 INFO - 'PWD': '/builds/slave/test', 07:43:30 INFO - 'SHELL': '/bin/bash', 07:43:30 INFO - 'SHLVL': '1', 07:43:30 INFO - 'TERM': 'linux', 07:43:30 INFO - 'TMOUT': '86400', 07:43:30 INFO - 'USER': 'cltbld', 07:43:30 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:30 INFO - '_': '/tools/buildbot/bin/python'} 07:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:31 INFO - Downloading/unpacking jsonschema==2.5.1 07:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:43:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:43:33 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 07:43:33 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 07:43:33 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 07:43:33 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 07:43:33 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 07:43:33 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 07:43:33 INFO - Downloading functools32-3.2.3-2.tar.gz 07:43:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:43:33 INFO - warning: no files found matching '*.txt' 07:43:33 INFO - no previously-included directories found matching 'build' 07:43:33 INFO - no previously-included directories found matching 'dist' 07:43:33 INFO - no previously-included directories found matching '.git*' 07:43:33 INFO - Installing collected packages: jsonschema, functools32 07:43:33 INFO - Running setup.py install for functools32 07:43:33 INFO - warning: no files found matching '*.txt' 07:43:33 INFO - no previously-included directories found matching 'build' 07:43:33 INFO - no previously-included directories found matching 'dist' 07:43:33 INFO - no previously-included directories found matching '.git*' 07:43:33 INFO - Successfully installed jsonschema functools32 07:43:33 INFO - Cleaning up... 07:43:33 INFO - Return code: 0 07:43:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:33 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:33 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 07:43:33 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 07:43:33 INFO - Using env: {'DISPLAY': ':0', 07:43:33 INFO - 'HOME': '/home/cltbld', 07:43:33 INFO - 'LANG': 'en_US.UTF-8', 07:43:33 INFO - 'LANGUAGE': 'en_US:en', 07:43:33 INFO - 'LOGNAME': 'cltbld', 07:43:33 INFO - 'MAIL': '/var/mail/cltbld', 07:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:33 INFO - 'MOZ_NO_REMOTE': '1', 07:43:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:33 INFO - 'NO_EM_RESTART': '1', 07:43:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:33 INFO - 'PWD': '/builds/slave/test', 07:43:33 INFO - 'SHELL': '/bin/bash', 07:43:33 INFO - 'SHLVL': '1', 07:43:33 INFO - 'TERM': 'linux', 07:43:33 INFO - 'TMOUT': '86400', 07:43:33 INFO - 'USER': 'cltbld', 07:43:33 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:33 INFO - '_': '/tools/buildbot/bin/python'} 07:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:43:33 INFO - Cleaning up... 07:43:33 INFO - Return code: 0 07:43:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:33 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:33 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 07:43:33 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 07:43:33 INFO - Using env: {'DISPLAY': ':0', 07:43:33 INFO - 'HOME': '/home/cltbld', 07:43:33 INFO - 'LANG': 'en_US.UTF-8', 07:43:33 INFO - 'LANGUAGE': 'en_US:en', 07:43:33 INFO - 'LOGNAME': 'cltbld', 07:43:33 INFO - 'MAIL': '/var/mail/cltbld', 07:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:33 INFO - 'MOZ_NO_REMOTE': '1', 07:43:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:33 INFO - 'NO_EM_RESTART': '1', 07:43:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:33 INFO - 'PWD': '/builds/slave/test', 07:43:33 INFO - 'SHELL': '/bin/bash', 07:43:33 INFO - 'SHLVL': '1', 07:43:33 INFO - 'TERM': 'linux', 07:43:33 INFO - 'TMOUT': '86400', 07:43:33 INFO - 'USER': 'cltbld', 07:43:33 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:33 INFO - '_': '/tools/buildbot/bin/python'} 07:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:34 INFO - Downloading/unpacking blobuploader==1.2.4 07:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:36 INFO - Downloading blobuploader-1.2.4.tar.gz 07:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:43:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:43:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:43:36 INFO - Downloading docopt-0.6.1.tar.gz 07:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:43:36 INFO - Installing collected packages: blobuploader, requests, docopt 07:43:36 INFO - Running setup.py install for blobuploader 07:43:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:43:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:43:36 INFO - Running setup.py install for requests 07:43:37 INFO - Running setup.py install for docopt 07:43:37 INFO - Successfully installed blobuploader requests docopt 07:43:37 INFO - Cleaning up... 07:43:37 INFO - Return code: 0 07:43:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:37 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')]} 07:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:37 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')]} 07:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:37 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:37 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 07:43:37 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 07:43:37 INFO - Using env: {'DISPLAY': ':0', 07:43:37 INFO - 'HOME': '/home/cltbld', 07:43:37 INFO - 'LANG': 'en_US.UTF-8', 07:43:37 INFO - 'LANGUAGE': 'en_US:en', 07:43:37 INFO - 'LOGNAME': 'cltbld', 07:43:37 INFO - 'MAIL': '/var/mail/cltbld', 07:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:37 INFO - 'MOZ_NO_REMOTE': '1', 07:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:37 INFO - 'NO_EM_RESTART': '1', 07:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:37 INFO - 'PWD': '/builds/slave/test', 07:43:37 INFO - 'SHELL': '/bin/bash', 07:43:37 INFO - 'SHLVL': '1', 07:43:37 INFO - 'TERM': 'linux', 07:43:37 INFO - 'TMOUT': '86400', 07:43:37 INFO - 'USER': 'cltbld', 07:43:37 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:37 INFO - '_': '/tools/buildbot/bin/python'} 07:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:43:37 INFO - Running setup.py (path:/tmp/pip-ir79G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:43:37 INFO - Running setup.py (path:/tmp/pip-Fp3tiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:43:37 INFO - Running setup.py (path:/tmp/pip-YwqchK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:43:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:43:37 INFO - Running setup.py (path:/tmp/pip-RsVUwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:43:38 INFO - Running setup.py (path:/tmp/pip-vd87rS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:43:38 INFO - Running setup.py (path:/tmp/pip-rUyEXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:43:38 INFO - Running setup.py (path:/tmp/pip-zlwxvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:43:38 INFO - Running setup.py (path:/tmp/pip-SqZBfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:43:38 INFO - Running setup.py (path:/tmp/pip-lg8zqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:43:38 INFO - Running setup.py (path:/tmp/pip-TVkxYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:43:38 INFO - Running setup.py (path:/tmp/pip-K5y_hQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:43:38 INFO - Running setup.py (path:/tmp/pip-QqdkNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:43:38 INFO - Running setup.py (path:/tmp/pip-4WQgVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:43:38 INFO - Running setup.py (path:/tmp/pip-HGawOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:38 INFO - Running setup.py (path:/tmp/pip-6WiXpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:43:38 INFO - Running setup.py (path:/tmp/pip-z9OveS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:43:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:43:38 INFO - Running setup.py (path:/tmp/pip-XHlsZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:43:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:43:38 INFO - Running setup.py install for manifestparser 07:43:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozcrash 07:43:39 INFO - Running setup.py install for mozdebug 07:43:39 INFO - Running setup.py install for mozdevice 07:43:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozfile 07:43:39 INFO - Running setup.py install for mozhttpd 07:43:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozinfo 07:43:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozInstall 07:43:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for mozleak 07:43:39 INFO - Running setup.py install for mozlog 07:43:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:43:39 INFO - Running setup.py install for moznetwork 07:43:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Running setup.py install for mozprocess 07:43:40 INFO - Running setup.py install for mozprofile 07:43:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Running setup.py install for mozrunner 07:43:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Running setup.py install for mozscreenshot 07:43:40 INFO - Running setup.py install for moztest 07:43:40 INFO - Running setup.py install for mozversion 07:43:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:43:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:43:40 INFO - Cleaning up... 07:43:40 INFO - Return code: 0 07:43:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:40 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:40 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 07:43:40 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 07:43:40 INFO - Using env: {'DISPLAY': ':0', 07:43:40 INFO - 'HOME': '/home/cltbld', 07:43:40 INFO - 'LANG': 'en_US.UTF-8', 07:43:40 INFO - 'LANGUAGE': 'en_US:en', 07:43:40 INFO - 'LOGNAME': 'cltbld', 07:43:40 INFO - 'MAIL': '/var/mail/cltbld', 07:43:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:40 INFO - 'MOZ_NO_REMOTE': '1', 07:43:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:40 INFO - 'NO_EM_RESTART': '1', 07:43:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:40 INFO - 'PWD': '/builds/slave/test', 07:43:40 INFO - 'SHELL': '/bin/bash', 07:43:40 INFO - 'SHLVL': '1', 07:43:40 INFO - 'TERM': 'linux', 07:43:40 INFO - 'TMOUT': '86400', 07:43:40 INFO - 'USER': 'cltbld', 07:43:40 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:40 INFO - '_': '/tools/buildbot/bin/python'} 07:43:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:43:40 INFO - Running setup.py (path:/tmp/pip-PidDid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:43:41 INFO - Running setup.py (path:/tmp/pip-X5KtnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:43:41 INFO - Running setup.py (path:/tmp/pip-9d78g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:43:41 INFO - Running setup.py (path:/tmp/pip-adUbKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:43:41 INFO - Running setup.py (path:/tmp/pip-rfXO5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:43:41 INFO - Running setup.py (path:/tmp/pip-e0lEF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:43:41 INFO - Running setup.py (path:/tmp/pip-Y7pFet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:43:41 INFO - Running setup.py (path:/tmp/pip-0NkeHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:43:41 INFO - Running setup.py (path:/tmp/pip-YoR0Ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:43:41 INFO - Running setup.py (path:/tmp/pip-14HnEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:43:41 INFO - Running setup.py (path:/tmp/pip-_05PlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:43:41 INFO - Running setup.py (path:/tmp/pip-L1S9z5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:43:41 INFO - Running setup.py (path:/tmp/pip-okorwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:43:41 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)) 07:43:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:43:41 INFO - Running setup.py (path:/tmp/pip-XArZ0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:42 INFO - Running setup.py (path:/tmp/pip-fnQhM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:43:42 INFO - Running setup.py (path:/tmp/pip-tevqyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:43:42 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)) 07:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:43:42 INFO - Running setup.py (path:/tmp/pip-lpCy88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:43:42 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)) 07:43:42 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)) 07:43:42 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)) 07:43:42 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)) 07:43:42 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)) 07:43:42 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)) 07:43:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:43:42 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 07:43:42 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 07:43:42 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 07:43:42 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 07:43:42 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 07:43:42 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 07:43:44 INFO - Downloading blessings-1.6.tar.gz 07:43:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:43:44 INFO - Installing collected packages: blessings 07:43:44 INFO - Running setup.py install for blessings 07:43:44 INFO - Successfully installed blessings 07:43:44 INFO - Cleaning up... 07:43:44 INFO - Return code: 0 07:43:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:43:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:43:44 INFO - Reading from file tmpfile_stdout 07:43:44 INFO - Current package versions: 07:43:44 INFO - argparse == 1.2.1 07:43:44 INFO - blessings == 1.6 07:43:44 INFO - blobuploader == 1.2.4 07:43:44 INFO - docopt == 0.6.1 07:43:44 INFO - functools32 == 3.2.3-2 07:43:44 INFO - jsonschema == 2.5.1 07:43:44 INFO - manifestparser == 1.1 07:43:44 INFO - mozInstall == 1.12 07:43:44 INFO - mozcrash == 1.0 07:43:44 INFO - mozdebug == 0.1 07:43:44 INFO - mozdevice == 0.49 07:43:44 INFO - mozfile == 1.2 07:43:44 INFO - mozhttpd == 0.7 07:43:44 INFO - mozinfo == 0.9 07:43:44 INFO - mozleak == 0.1 07:43:44 INFO - mozlog == 3.4 07:43:44 INFO - moznetwork == 0.27 07:43:44 INFO - mozprocess == 0.24 07:43:44 INFO - mozprofile == 0.28 07:43:44 INFO - mozrunner == 6.13 07:43:44 INFO - mozscreenshot == 0.1 07:43:44 INFO - mozsystemmonitor == 0.3 07:43:44 INFO - moztest == 0.8 07:43:44 INFO - mozversion == 1.4 07:43:44 INFO - psutil == 3.1.1 07:43:44 INFO - requests == 1.2.3 07:43:44 INFO - wsgiref == 0.1.2 07:43:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:44 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:44 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 07:43:44 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 07:43:44 INFO - Using env: {'DISPLAY': ':0', 07:43:44 INFO - 'HOME': '/home/cltbld', 07:43:44 INFO - 'LANG': 'en_US.UTF-8', 07:43:44 INFO - 'LANGUAGE': 'en_US:en', 07:43:44 INFO - 'LOGNAME': 'cltbld', 07:43:44 INFO - 'MAIL': '/var/mail/cltbld', 07:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:44 INFO - 'MOZ_NO_REMOTE': '1', 07:43:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:44 INFO - 'NO_EM_RESTART': '1', 07:43:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:44 INFO - 'PWD': '/builds/slave/test', 07:43:44 INFO - 'SHELL': '/bin/bash', 07:43:44 INFO - 'SHLVL': '1', 07:43:44 INFO - 'TERM': 'linux', 07:43:44 INFO - 'TMOUT': '86400', 07:43:44 INFO - 'USER': 'cltbld', 07:43:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:44 INFO - '_': '/tools/buildbot/bin/python'} 07:43:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 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)) 07:43:44 INFO - Cleaning up... 07:43:44 INFO - Return code: 0 07:43:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:43:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:44 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 0x26701f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, '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': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', '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 07:43:44 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 07:43:44 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 07:43:44 INFO - Using env: {'DISPLAY': ':0', 07:43:44 INFO - 'HOME': '/home/cltbld', 07:43:44 INFO - 'LANG': 'en_US.UTF-8', 07:43:44 INFO - 'LANGUAGE': 'en_US:en', 07:43:44 INFO - 'LOGNAME': 'cltbld', 07:43:44 INFO - 'MAIL': '/var/mail/cltbld', 07:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:44 INFO - 'MOZ_NO_REMOTE': '1', 07:43:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:44 INFO - 'NO_EM_RESTART': '1', 07:43:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:44 INFO - 'PWD': '/builds/slave/test', 07:43:44 INFO - 'SHELL': '/bin/bash', 07:43:44 INFO - 'SHLVL': '1', 07:43:44 INFO - 'TERM': 'linux', 07:43:44 INFO - 'TMOUT': '86400', 07:43:44 INFO - 'USER': 'cltbld', 07:43:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:44 INFO - '_': '/tools/buildbot/bin/python'} 07:43:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:43:45 INFO - Cleaning up... 07:43:45 INFO - Return code: 0 07:43:45 INFO - Running post-action listener: _resource_record_post_action 07:43:45 INFO - Running post-action listener: _start_resource_monitoring 07:43:45 INFO - Starting resource monitoring. 07:43:45 INFO - [mozharness: 2017-02-17 15:43:45.196717Z] Finished create-virtualenv step (success) 07:43:45 INFO - [mozharness: 2017-02-17 15:43:45.197179Z] Running install step. 07:43:45 INFO - Running pre-action listener: _resource_record_pre_action 07:43:45 INFO - Running main action method: install 07:43:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:43:45 INFO - Reading from file tmpfile_stdout 07:43:45 INFO - Detecting whether we're running mozinstall >=1.0... 07:43:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:43:45 INFO - Reading from file tmpfile_stdout 07:43:45 INFO - Output received: 07:43:45 INFO - Usage: mozinstall [options] installer 07:43:45 INFO - Options: 07:43:45 INFO - -h, --help show this help message and exit 07:43:45 INFO - -d DEST, --destination=DEST 07:43:45 INFO - Directory to install application into. [default: 07:43:45 INFO - "/builds/slave/test"] 07:43:45 INFO - --app=APP Application being installed. [default: firefox] 07:43:45 INFO - mkdir: /builds/slave/test/build/application 07:43:45 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'] 07:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:44:00 INFO - Reading from file tmpfile_stdout 07:44:00 INFO - Output received: 07:44:00 INFO - /builds/slave/test/build/application/firefox/firefox 07:44:00 INFO - Running post-action listener: _resource_record_post_action 07:44:00 INFO - [mozharness: 2017-02-17 15:44:00.874972Z] Finished install step (success) 07:44:00 INFO - [mozharness: 2017-02-17 15:44:00.875245Z] Running run-tests step. 07:44:00 INFO - Running pre-action listener: _resource_record_pre_action 07:44:00 INFO - Running main action method: run_tests 07:44:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:44:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:44:00 INFO - Python 2.7.3 07:44:00 INFO - Return code: 0 07:44:00 INFO - grabbing minidump binary from tooltool 07:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:00 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 0x290b400>, '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 0x290c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x290c840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:44:00 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 07:44:00 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 07:44:00 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 07:44:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:44:00 INFO - Return code: 0 07:44:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:44:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:44:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:44:00 INFO - ENV: RUST_BACKTRACE is now 1 07:44:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:44:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:44:00 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/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 07:44:00 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/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 07:44:00 INFO - Using env: {'DISPLAY': ':0', 07:44:00 INFO - 'HOME': '/home/cltbld', 07:44:00 INFO - 'LANG': 'en_US.UTF-8', 07:44:00 INFO - 'LANGUAGE': 'en_US:en', 07:44:00 INFO - 'LOGNAME': 'cltbld', 07:44:00 INFO - 'MAIL': '/var/mail/cltbld', 07:44:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:44:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:44:00 INFO - 'MOZ_NO_REMOTE': '1', 07:44:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:44:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:44:00 INFO - 'NO_EM_RESTART': '1', 07:44:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:44:00 INFO - 'PWD': '/builds/slave/test', 07:44:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:44:00 INFO - 'RUST_BACKTRACE': '1', 07:44:00 INFO - 'SHELL': '/bin/bash', 07:44:00 INFO - 'SHLVL': '1', 07:44:00 INFO - 'TERM': 'linux', 07:44:00 INFO - 'TMOUT': '86400', 07:44:00 INFO - 'USER': 'cltbld', 07:44:00 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003', 07:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:44:00 INFO - '_': '/tools/buildbot/bin/python'} 07:44:00 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/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 07:44:01 INFO - mozversion application_buildid: 20170217150643 07:44:01 INFO - mozversion application_changeset: df9a0acc26486790c8794b787c764654d32c3f9c 07:44:01 INFO - mozversion application_display_name: Nightly 07:44:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:44:01 INFO - mozversion application_name: Firefox 07:44:01 INFO - mozversion application_remotingname: firefox 07:44:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:44:01 INFO - mozversion application_vendor: Mozilla 07:44:01 INFO - mozversion application_version: 54.0a1 07:44:01 INFO - mozversion platform_buildid: 20170217150643 07:44:01 INFO - mozversion platform_changeset: df9a0acc26486790c8794b787c764654d32c3f9c 07:44:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:44:01 INFO - mozversion platform_version: 54.0a1 07:44:01 INFO - using testdate: 1487346241 07:44:01 INFO - actual date: 1487346241 07:44:01 INFO - starting webserver on 'localhost:50209' 07:44:01 INFO - SUITE-START | Running 2 tests 07:44:01 INFO - TEST-START | dromaeo_css 07:44:01 INFO - operating with platform_type : linux_ 07:44:01 INFO - Initialising browser for dromaeo_css test... 07:44:01 INFO - TEST-INFO | started process 15549 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo5ajvd/profile http://localhost:50209/getInfo.html) 07:44:04 INFO - PROCESS | 15549 | Iterating over profile /tmp/tmpo5ajvd/profile 07:44:05 INFO - PROCESS | 15549 | __metrics Screen width/height:1600/1200 07:44:05 INFO - PROCESS | 15549 | colorDepth:24 07:44:05 INFO - PROCESS | 15549 | Browser inner width/height: 1024/697 07:44:05 INFO - PROCESS | 15549 | __metrics 07:44:05 INFO - PROCESS | 15549 | Stat failure 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/frequencyCap.json = Fri Feb 17 2017 07:44:03 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/extensions.ini = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/bookmarkbackups = Fri Feb 17 2017 07:44:03 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/blocklist.xml = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/key3.db = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/cookies.sqlite = Fri Feb 17 2017 07:44:05 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/safebrowsing = Fri Feb 17 2017 07:44:05 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/extensions.json = Fri Feb 17 2017 07:44:03 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/places.sqlite-wal = Fri Feb 17 2017 07:44:05 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/cert8.db = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/directoryLinks.json = Fri Feb 17 2017 07:44:03 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/mimeTypes.rdf = Fri Feb 17 2017 07:44:05 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/cache2 = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/user.js = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/pluginreg.dat = Fri Feb 17 2017 07:44:03 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/crashes = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | Stat failure 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/permissions.sqlite = Fri Feb 17 2017 07:44:02 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/compatibility.ini = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/gmp = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/addons.json = Fri Feb 17 2017 07:44:03 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/localstore.rdf = Fri Feb 17 2017 07:43:21 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/.parentlock = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/thumbnails = Fri Feb 17 2017 07:44:03 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/places.sqlite = Fri Feb 17 2017 07:44:05 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/secmod.db = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/minidumps = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/content-prefs.sqlite = Fri Feb 17 2017 07:44:03 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/places.sqlite-shm = Fri Feb 17 2017 07:44:02 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/prefs.js = Fri Feb 17 2017 07:43:21 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/startupCache = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/sessionCheckpoints.json = Fri Feb 17 2017 07:44:05 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/extensions = Fri Feb 17 2017 07:44:01 GMT-0800 (PST) 07:44:05 INFO - PROCESS | 15549 | No birth date. Using mtime. 07:44:05 INFO - PROCESS | 15549 | Using date: /tmp/tmpo5ajvd/profile/webappsstore.sqlite = Fri Feb 17 2017 07:44:05 GMT-0800 (PST) 07:44:06 INFO - PROCESS | 15549 | 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" 07:44:06 INFO - PROCESS | 15549 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:44:08 INFO - TEST-INFO | 15549: exit 0 07:44:08 INFO - Browser initialized. 07:44:08 INFO - Running cycle 1/1 for dromaeo_css test... 07:44:08 INFO - TEST-INFO | started process 15637 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo5ajvd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:20 INFO - PROCESS | 15637 | RSS: Main: 147095552 07:44:20 INFO - PROCESS | 15637 | 07:47:26 INFO - PROCESS | 15637 | Cycle 1(1): loaded http://localhost:50209/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50209/tests/dromaeo/cssquery-ext.html) 07:47:28 INFO - PROCESS | 15637 | RSS: Main: 272650240 07:47:28 INFO - PROCESS | 15637 | 07:49:10 INFO - PROCESS | 15637 | ************************* 07:49:10 INFO - PROCESS | 15637 | A coding exception was thrown and uncaught in a Task. 07:49:10 INFO - PROCESS | 15637 | 07:49:10 ERROR - PROCESS | 15637 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:49:10 INFO - PROCESS | 15637 | Full stack: 07:49:10 INFO - PROCESS | 15637 | ************************* 07:50:31 INFO - PROCESS | 15637 | Cycle 1(1): loaded http://localhost:50209/tests/dromaeo/cssquery-ext.html (next: http://localhost:50209/tests/dromaeo/cssquery-jquery.html) 07:50:31 INFO - PROCESS | 15637 | RSS: Main: 244744192 07:50:31 INFO - PROCESS | 15637 | 07:53:33 INFO - PROCESS | 15637 | Cycle 1(1): loaded http://localhost:50209/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50209/tests/dromaeo/cssquery-mootools.html) 07:53:34 INFO - PROCESS | 15637 | RSS: Main: 208965632 07:53:34 INFO - PROCESS | 15637 | 07:56:36 INFO - PROCESS | 15637 | Cycle 1(1): loaded http://localhost:50209/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50209/tests/dromaeo/cssquery-prototype.html) 07:56:37 INFO - PROCESS | 15637 | RSS: Main: 181587968 07:56:37 INFO - PROCESS | 15637 | 07:59:40 INFO - PROCESS | 15637 | Cycle 1(1): loaded http://localhost:50209/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50209/tests/dromaeo/cssquery-yui.html) 07:59:41 INFO - PROCESS | 15637 | RSS: Main: 185004032 07:59:41 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | Cycle 1(1): loaded http://localhost:50209/tests/dromaeo/cssquery-yui.html (next: http://localhost:50209/tests/dromaeo/cssquery-dojo.html) 08:02:44 INFO - PROCESS | 15637 | RSS: Main: 272842752 08:02:44 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | __start_tp_report 08:02:44 INFO - PROCESS | 15637 | _x_x_mozilla_page_load 08:02:44 INFO - PROCESS | 15637 | _x_x_mozilla_page_load_details 08:02:44 INFO - PROCESS | 15637 | |i|pagename|runs| 08:02:44 INFO - PROCESS | 15637 | |0;dojo.html;2548.7068987066486;2572.8584927828983;2578.79369650428;2604.544204764166;2610.034287313694;4535.705179163352;4574.908501829969;4642.35007099006;4659.906801863961;4674.158651442739;4589.839355622554;4633.559811817872;4645.233536466488;4665.790039448225;4683.852456148248;4237.216115018724;4278.272693642089;4291.742495450283;4352.238358287303;4374.60628543431;4370.2352088384405;4405.779711014453;4417.138657961683;4419.403380543634;4446.955530444703;4194.3918131870705;4219.641330486912;4236;4271.444712187426;4380.3210502372185;4255.87232383029;4283.635890949273;4325.82696692132;4334.934975975363;4381.342798580238;4331.848385306499;4340.348947657845;4360.869173924755;4384.386185933972;4390.407295015164;4660.603848672867;4709.434867815849;4715.905681886377;4733.479317275097;4769.046190761862;4802.06359759847;4825.179719447668;4832.251001094836;5066.189409694432;5072.898542029176;4913.557779799835;4927.359443272388;4928.359313289249;4928.507149285078;4933.210686290176;4627.930581041287;4663.836765713184;4692.81228750853;4709.882252943636;4769.833055843098;5154.690718556823;5155.149400348975;5208.5312321891215;5211.609129315317;5214.374275086938;4654.418197725285;4733.455652599926;4734.668573199843;4799.760011999386;4910.312556242129;4872.342233798392;4877.561019508261;4880.487548807395;4881.804727810919;4884.975575122173;4835.323054772335;4840.75796210188;4847.660663753503;4993.051320249214;5013.573846223039;5052.646314758005;5054.039732450822;5065.924011139836;5066.797328106834;5103.464136265713;4161;4163.604457576525;4175.248455278019;4189.4344263524035;4208.85269015583;3660.0666829958723;3689.114612493036;3693.076730817296;3704.5554533456157;3710.276496083292;4312.633426158749;4318.611324980767;4319.568043195655;4323.610875021263;4363.3018717005125;4726.456457507431;4738.336632871401;4742.786574604151;4969.9563091750415;5003.974980125076;4195.559466255999;4219.51475580306;4243.469566304212;4367.148406060787;4424.093060922503;4626.190416677068;4656.953430465652;4657.790399432034;4662.557057079572;4665.836695715634;4851.975740121276;4857.635677324144;4862.294967229661;4864.416270047617;4909.288153217834;4914.0908931847725;5090.185570308733;5138.563222126161;5144.536991670618;5195.038917800219;4693.671442998957;4764.475907650225;4789.640776941674;4827.8551643450755;4895.681780684244;5034.572061374824;5044.848654540369;5047.318611987482;5088.542031217208;5090.160123579568;5108.489151084862;5120.25756265332;5121;5121.436641969306;5126.102931986962;4674;4755.310479980452;4833.202521583899;4974.427940786856;4982.526659967442;4804.711717296974;4914.631402644887;4923.261510773413;4930.087933732271;4939.160342741814;4829.903401932012;4837.322774811459;4840.177169881199;4854.05345957549;4887;4899.975500122477;4913.754312284443;4918.778654960463;4921.876953076202;4981.402231732215;4575.3365761965;4599.747013914267;4603.217453032926;4815.95184048155;4830.420349558076 08:02:44 INFO - PROCESS | 15637 | |1;ext.html;550.3148580017878;554.2268535393126;577.0622738050665;580.9506191973682;602.5601311042939;27221.319467013334;27776;39040.41439378408;40359.79820100903;40660;41015.38476922848;41441;41467;41486.792566037206;41500.58499415005;7449.627518624061;7678.961605191994;7686.807829804272;7695.038120234971;7697.653605587734;6738.12404387429;6888;9344.626214951393;9561.043895610454;9590.520473976274;9680.273979451535;9725.076117768836;9733.513324333755;9741.415515069084;9743;9639.373440726366;9704.320697551175;9714.854277185848;9717.514124293792;9739.123478886933;9439.150476457151;9499.620015199383;9501.4299142051;9513.001134880808;9513.33406661534;36788.44817327742;37181.62818371809;37205.441918371245;37217;37252.81373593142;3422.606400263952;3470.9132271693156;3478.9478157827652;3510.157562185057;3538.840752166133;26887.32781680454;27717;27755;28038.31770093801;28841.278968025756;3392.4911263310455;3446.3968805458944;3521.3485505181366;3525.435930251173;3660.633936606345;219926;220743;221469;221840;222066.99999999837;43936.3409548857;47417.525824742;47562.76218618954;47591.28613070807;47628.28557571639;48603;55122;57418.712906436034;57481;62732.09946905837;11463.993801084776;11532.654020379403;11559.537618495166;11584.189106762446;11604.825927611093;8450.492970421794;8512.106228846005;8517.403781735344;8518.82962340762;8528.74413767588;16697.41374569414;16710.916445417693;16719;16759;16803.579910502092;6193.504519638418;6258.217722784652;6270.717817698214;6282.874342513215;6423.100765892778;767.6583920155477;788.4953629677011;789.1398375770323;821.5768879027306;821.954792486407;25770;26085.478290434086;26158.80031256374;26412.33969150748;26438.206853794418;901.0718959471754;935.5836652689488;938.1369140390857;939.769756409684;942.0673533202217;13059.21644701321;13809.447622095004;13855.930720346334;13975.650608734659;14131.505397311044;128245;128815.35592322166;128972;128997.35501322434;129127.35436322758;47419.28871066937;47465.28802067972;47520.76239618849;47551;47605.52394476011;1133.4955944604737;1172.941352932367;1178.935158566287;1184.3071802995173;1188.851393575803;24269;25079.24762257135;26027.609585855847;26051.739482605688;26199.738002620492;40793.388099177944;41185;41259;41275.38086928758;41290.38064429096;265858;268054;268178;268258;268418;28797.848230517935;31081.53377699291;34422;35263.82368088143;35325.116872078404;39203;39260.60739392684;39696.22885825842;40285.1942961145;40406.19187616173;33976;34377.48433773445;34541.82729086339;34707.917524948585;35094.649053509136;32742.34515309728;33149.17127071843;33162;33775.49336759902;33825;32990;34033.31933361269;34436.65563344335;34439;34466.483002754474;28017.299567510974;29291.41417171602;30398.54402184013;30463.543046854757;30615.387692246477;22897.313080607608;23172.884135579214;23369.182078627167;23393.649095263245;23525.764742352356 08:02:44 INFO - PROCESS | 15637 | |2;jquery.html;338.9762915993701;366.1286138989207;369.21173295015143;371.48549259275893;395.3299157927314;12446.377681115942;12625.621231363062;13848.792268115974;18146.274149034056;18249.0875456227;18189.636207275846;18407.815921840778;18823.247070117213;18958.810411895876;19195.32816351428;5172.146595811687;5349.411564727877;5377.327834020747;5383;5388.407275199725;5065.544101030907;5190.480951904817;5282.867928301785;5384.757685904124;5386.73066346683;5169.56058735008;5176.55999240065;5183.818566350178;5185.585153187755;5195.40252870919;5039.3196918415915;5171.4311425743135;5192.013517431695;5193.376794784613;5234.476552344735;4698.318743782167;4726.125666751662;4729.668923175379;4729.929051064237;4731.716097034155;18356.54108647281;20329.796702032938;21670.78329216719;22012.779872201234;22214.777852221432;2086.1446806809136;2187.814035806958;2227.988860055689;2260.2315212827716;2552.7574880386333;9249.491277979709;9345.345825792198;9426.528673566294;9650.71047868565;9882.209423246195;1695.9406420775338;1702.3445973300245;1710.7471153417796;1735.3405705831829;1753.1672455583623;70660.64669676671;72506.63746681233;72582.63708681475;73692;73879.63060184718;9739.659111931049;9881.677805715652;10300.175985921258;10442.738931526772;10718.73203169927;13167.934160329138;13205.669858253621;13261.204327740366;13264.336783160803;13305.201687898758;6307.180066591313;6421.711023003976;6520.543561950618;6520.608763474207;6624.337566243337;5805.390434004453;5840.912386314209;5948.375420580864;5960.195373624592;5992.191054207713;4186.979065104716;4209.368594710757;4412.646988240933;4543.818247270138;4672.649551283667;1825.8174182581902;1876.1651065276094;1902.353199912009;1930.420873737873;1930.6331796958814;713.5362014690493;738.0774032459426;752.1838804896748;754.607604045893;765.2385876053363;17140.634195485443;17225.39711110105;18085.547861303574;18346.082695865152;18422.723659145122;727.0911360799001;728.0862517540453;743.1565173528085;744.6388501576731;751.2975368030909;9519.381240219363;9832.213422926148;9958.402495850272;10012.69961901144;10038.749031274276;416446;435626;460518;461925;463184;80401.59799201084;80920;82617.58691206506;85460;87340.56329718311;929.3726734454351;930.2697382554662;966.1401352796017;966.40566051879;968.4479846487575;14887.255637218312;15046.400127588171;15564.92217538905;15887.68224635524;15939.840601593836;33216.1695957603;38237.42643860289;38390;38422.615773841906;39240.20894254103;51809.740951295;51978.48021519736;52720.209196862845;53896.73051634717;53979.173019912676;7532.43506736986;7571.848563028819;7875.409344299315;7890.447668663139;8145.42981991255;9479.715608531755;12149.028077753936;12178.147529672839;12278.631641050782;12554.121211515107;9221.677241296522;9255.722328330161;9396;9565.952170239105;9713.854292185482;9808.460534670376;9932.155766759617;9941.403515789076;9975.551100200364;10241.283110182216;10297.74255643615;10385.584576616851;10432.895671043496;10820.458977051274;10843.240973131806;7539.208383119693;7670.386369090571;7735.419843511647;7752.883706744291;7817.452778305692;9288;10363.32638378503;10382.065614094465;10443.477826108816;10478.273839117215 08:02:44 INFO - PROCESS | 15637 | |3;mootools.html;1132.3319241647425;1327.130729372261;1336.1718675993282;1342.201390172847;1344.7579435701575;6125.754969801208;6897.482688798342;7066.6113363765035;7112.395446387063;7300.817479563014;7188.388986936116;7254.564726116437;7257.310555497219;7260.528065675728;7261.673224704888;1733.159417682423;2010.9296174633891;2046.4167712202025;2049.313479984207;2049.4568939231117;1805.4493379519242;2017.6872584749315;2083.4166433398673;2084.593504266669;2102.4743814046487;2013.1242909334487;2014.033264033265;2018.6669199582052;2021.7169596256535;2021.9191232350688;1972.0435588739522;2001.0895042755512;2007.8293345065613;2009.0657844102416;2009.678451447776;1983.1274239334646;1998.1008546154144;2037.4397040813747;2040.1941233212797;2044.8159665630164;7116.110486189226;7141.178764442052;7171.856562868714;7174.964125179393;7176;4133.793310334501;4293.720908140992;4297.699161058727;4298.591633794785;4299.591538803808;2555.4505781256935;2729.467753788012;2732.8223665461683;2766.8478233697138;2778.499870023395;1037.2428127466992;1064.6646306413454;1115.2249186815168;1125.401929260452;1130.7229728716518;9292.767680808034;9356.204722598588;9360.391574547633;9368.39105458143;9369.016253293374;3715.1826598148364;3963.227170701743;3974.2448934702315;4011.2579172853116;4038.2327357801923;8206.712765053315;8209.38429617781;8215.671373145127;8216.137305582948;8222.547759873145;4295.591918767775;4302.526722060571;4302.763348015889;4304.268274393361;4312.460942382202;3350.329934013207;3394.2193295542165;3885.9805700971315;3956.742811717229;3959.2279505496726;3062.2344413896526;3335.5657067461225;3342.298117395326;3384.5600071990484;3392.3045775615938;1089.5805115030612;1090.3022065877844;1103.0679076180616;1119.2948442481347;1123.915706322029;780.5667854340782;784.6861255497846;795.5385876191795;796.0964305513285;802.450321529754;4502.932456013163;4567.520410356865;4630.536946305342;4633.166030114612;4710.552497512731;858.2618947704968;872.437277955715;880.0847118996172;880.1286726141202;882.7042940614838;3049.771267154972;3055.220918665726;3099.783015188915;3155.5108958111527;3192.1221664042573;8440.240378365976;8579.656813727506;8582.356323275779;8598.05421403645;8639.308855291561;8605.784855378666;8608.827823443497;8613;8613.569321533898;8615.956920215358;6238.594491358047;6485.675716214265;6623.4038936495945;6790.287019862942;6801.387875091265;4626.213543697647;4676.415448068991;4677.111348843709;4701.119552179019;4747.050589881968;7680.615969201406;7681.923180768121;7758.883616745867;7942.563159026078;7943.960280198562;7743.7676869694;7920.128785833669;7925.326347260317;7931.325837303893;7975.5613441261285;5474;5965.761369545169;6054.212952316171;6066.726997285221;6082.087686847009;6134.601250918676;6663.666816659244;6874.312568743086;6950.721971121098;7058.858822823618;6210.7515699371515;6401.295857455583;6462.483001359974;6521.771737989147;6612.404883560503;6284.371562843679;6373.745050198126;6410.551261411656;6494.9025764612625;6515.413612774873;5356.4911333424825;5527.861803454945;5533.557315414838;5533.612647114664;5533.944660553343;4147.81334839939;4178.22702799983;4234.42835217254;4316.784160791885;4371.256886329268;4179.97910010448;4297.441332626739;4333.436653235185;4347.521772605074;4412.492563355127 08:02:44 INFO - PROCESS | 15637 | |4;prototype.html;334.5015926269859;350.7176722738198;350.85965613754496;357.25334051831675;357.8121486219733;4744.33579298898;4753.691010084342;4796.273341597022;4796.352492413524;4819.180739274323;4732.46194986629;4745.738984355859;4772.947632518016;4776.928346074812;4787.976060119695;3598.7660802047913;3706.369917114084;3757.530308711411;3768.0768211788013;3790.1093243087853;3724.944125838115;3737.682297004758;3740.2706472237924;3758.6617204451595;3786.110264087937;3703.1112532992024;3721.441783732448;3725.1245957200035;3726.888193354204;3741.4387841823673;3424.9486257706153;3734.477173195755;3750.8559889233775;3759.6428339307995;3762.698984081267;3116.5481005254337;3117.703818137273;3122.1414111119398;3125.796823206484;3150.6061742282213;4607.378003969488;4633.32816741574;4645.698029628097;4650.511696271877;4668.7589956820575;4115.156392939441;4128.3784796654045;4129.97935010326;4139.212620452283;4142.958570414287;4712.740799256074;4936.950630493685;4956.08312462192;4965.361430727888;4992.450830408652;3572.214112895159;3585.8744943926836;3587.4798154267487;3603.1712706077487;3611.7652352596997;4965.776540055706;5066.771995260186;5086.821961231339;5237.188235823453;5252.290940722992;3831.1954489556947;3834.7123965702685;3923.6076392360537;3931.3513270310077;3937.0944682723134;3991.6207960243732;3994.78028708418;3996.200759848042;4000.6599439047427;4006.719529632898;4013.0451061146377;4112.856050038235;4394.0666106913495;4409.162259170747;4425.601695847325;4969.701817890938;4978.726170060681;5010.897602527438;5042.319286896295;5043.218301163325;1330.7671157547584;1333.6932505523785;1339.9598012059653;1345.3049158060915;1349.1702602899147;884.5135175653365;933.9066093390607;939.7227817793768;945.7588314979636;948.9525523723786;4283.700140990163;4285.742855428684;4290.570942905684;4298.849540266076;4299.022449673185;4932.432770231398;4944.703317800943;4982.202847544448;4994.575461085776;5017.422996355393;4276.508201556799;4302.44068271129;4307.892302692395;4312.849050283225;4314.956850431518;2498.9750102498742;2623.6851577810785;2637.5252454558363;2639.9076032338776;2643.5109504741686;4675.976620116946;4709.976450117728;4713.599344055725;4714.339992401066;4716.976415117902;4899.681520701143;5068.82259120929;5075.543201111917;5082.898342033139;5111.236623364933;4655.906881862344;4696.788644511005;4697.835575754901;4698.345434829311;4699.718016918996;4937.975310123426;4969.981153863377;4973.229149481836;4981.825636102719;5008.774605142705;4608.861734147981;4860.441049279237;4882.511748825089;4884.267359896044;4907.233395977517;4608.28571571407;4662.7202367858035;4672.785075880363;4678.8596342109795;4742.928856067231;3577.5349204603235;3661.4324779659196;3669.119411341312;3700.8149592520804;3707.9073023174637;5181.171012637959;5187;5188.818391356284;5194.53249207558;5195.74021298926;4865.221564549655;4995.275685025578;5003.849884503471;5030.346055012825;5049.2426136079885;4905.23968784839;4921.26181072842;4925.433575138905;4939.43196532389;4968.950310496849;4894.877628059327;4898;4903.754812259444;4908.901821963612;4914.311996320589;4746.762661866962;4997.20044792846;5002.723216293333;5014.548690617716;5027.497250274943;4873.853784386474;4875.926861097158;4889.926651100165;4890.584300334369;4898.657094003385 08:02:44 INFO - PROCESS | 15637 | |5;yui.html;657.4313219065509;678.7510979797172;730.987582198655;758.2228216078519;779.4076501858585;12499.18755280906;13541.255230962293;13549.593512194637;13566.728665426686;13569.45722171112;12676.366181690924;12706.047046471505;12720.236785792837;12723.872761272385;12757.234565926028;806.6612022950375;825.9297959673435;827.5117680568463;828.1676914700718;828.772087675888;849.3799526345771;852.4459101583989;852.4927668037539;852.5481494807731;853.4793775796759;884.9867251991225;885.4864178776327;886.57887503436;886.7605746448455;886.8980067292247;874.7987962768535;875.8379699755534;875.951822649754;876.1939016105199;876.912308769118;642.7204166187677;642.9421352078335;644.4296797334392;645.2643985856128;645.8449972006731;13336.933315333457;13361.73276534464;13449.798253026114;13523.39144738489;14102.224377659224;11767.28192010828;11811.822822657667;12750.234985900875;13418.530351437652;13621.455141794415;10606.416647084407;10738.08726258269;10772.892271077268;10785.83821242682;11018.44907754617;3200.3279311344645;3232.4828027515487;3253.9349213015844;3340.749443791701;3440.088376580208;59361.7031914842;60149.6992515039;60683;60912;61216.08070137128;9652.710418687451;9731.318807683536;9804.948356947923;9896.65361712351;9906.157976571927;19095.14071866769;19235.230590776213;19297;19313.613727725366;19318.227270909007;6195.535334849904;6297;6317.747290108436;6549.508786841006;6633.933660663428;3488.66857648523;3520.982395088008;3523.788572685647;3610.097475631092;3647.544056992876;2737.110439107298;2784.331760377495;2792.888284468639;2803.481355949156;2803.8598070096414;478.2563114357208;484.71644088208086;502.1714171616861;503.0668110654683;509.1344713986238;616.8457885528618;624.2820756130486;628.5788521690479;638.3425072175667;652.2824892618177;8255.463394879314;8858;9059.773505662411;9171.770705732277;9584.233261339215;570.5179123640518;591.6952769323801;601.1223613524304;613.365167052099;617.0713076819447;4240.830366785294;4324.935125973113;4362.389265502832;4404.911901761947;4435.822567097344;50607.240891385925;50668.239976400386;50856;58790;60802.39197608056;43209.13581728348;51082.97834043373;54204;55014;55841;46787.298190527166;47118;49258;49479.75260123676;50917;8178.7955301117945;8286.917130828615;8341.541215233221;8439.746807595782;8465.492070475791;34400.48399274063;34793.130171744895;35738.0344081078;36308.09229769277;36451.81774091112;42046.36930446107;43931;44184.558154418046;44895.77552112219;46117.30824037575;9786.75533111678;9984.450855203033;10002.599896004078;10322.741931451774;10370.222233332379;10683.198760092868;10835.187360947803;11258.043066339453;11436.542538298707;11448.82826757616;10797.784044319227;10875.347479151276;11050.557977680803;11077.058450031838;11134.109271258441;10416.583336666448;10794.838077428687;10815.729606759895;11247.437628118398;11362.829557556808;9998.800023999625;10085.58065479158;10097.495125243855;10136.087752102347;10141.898581014095;8885.42244754089;9004.279657627243;9008.189262966365;9067.68263110788;9217.078292170998;7547.849043019219;7843.372530197685;7922.960385198037;8008.359331253602;8229.300509456763 08:02:44 INFO - PROCESS | 15637 | __end_tp_report 08:02:44 INFO - PROCESS | 15637 | __start_cc_report 08:02:44 INFO - PROCESS | 15637 | _x_x_mozilla_cycle_collect,1466 08:02:44 INFO - PROCESS | 15637 | __end_cc_report 08:02:44 INFO - PROCESS | 15637 | __startTimestamp1487347364424__endTimestamp 08:02:44 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | ------- Summary: start ------- 08:02:44 INFO - PROCESS | 15637 | Number of tests: 6 08:02:44 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | [#0] dojo.html Cycles:165 Average:4627.10 Median:4738.34 stddev:491.80 (10.4%) stddev-sans-first:465.50 08:02:44 INFO - PROCESS | 15637 | Values: 2548.7 2572.9 2578.8 2604.5 2610.0 4535.7 4574.9 4642.4 4659.9 4674.2 4589.8 4633.6 4645.2 4665.8 4683.9 4237.2 4278.3 4291.7 4352.2 4374.6 4370.2 4405.8 4417.1 4419.4 4447.0 4194.4 4219.6 4236.0 4271.4 4380.3 4255.9 4283.6 4325.8 4334.9 4381.3 4331.8 4340.3 4360.9 4384.4 4390.4 4660.6 4709.4 4715.9 4733.5 4769.0 4802.1 4825.2 4832.3 5066.2 5072.9 4913.6 4927.4 4928.4 4928.5 4933.2 4627.9 4663.8 4692.8 4709.9 4769.8 5154.7 5155.1 5208.5 5211.6 5214.4 4654.4 4733.5 4734.7 4799.8 4910.3 4872.3 4877.6 4880.5 4881.8 4885.0 4835.3 4840.8 4847.7 4993.1 5013.6 5052.6 5054.0 5065.9 5066.8 5103.5 4161.0 4163.6 4175.2 4189.4 4208.9 3660.1 3689.1 3693.1 3704.6 3710.3 4312.6 4318.6 4319.6 4323.6 4363.3 4726.5 4738.3 4742.8 4970.0 5004.0 4195.6 4219.5 4243.5 4367.1 4424.1 4626.2 4657.0 4657.8 4662.6 4665.8 4852.0 4857.6 4862.3 4864.4 4909.3 4914.1 5090.2 5138.6 5144.5 5195.0 4693.7 4764.5 4789.6 4827.9 4895.7 5034.6 5044.8 5047.3 5088.5 5090.2 5108.5 5120.3 5121.0 5121.4 5126.1 4674.0 4755.3 4833.2 4974.4 4982.5 4804.7 4914.6 4923.3 4930.1 4939.2 4829.9 4837.3 4840.2 4854.1 4887.0 4900.0 4913.8 4918.8 4921.9 4981.4 4575.3 4599.7 4603.2 4816.0 4830.4 08:02:44 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | [#1] ext.html Cycles:180 Average:37295.94 Median:3599.74 stddev:55851.27 (1551.5%) stddev-sans-first:55939.80 08:02:44 INFO - PROCESS | 15637 | Values: 550.3 554.2 577.1 581.0 602.6 27221.3 27776.0 39040.4 40359.8 40660.0 41015.4 41441.0 41467.0 41486.8 41500.6 7449.6 7679.0 7686.8 7695.0 7697.7 6738.1 6888.0 9344.6 9561.0 9590.5 9680.3 9725.1 9733.5 9741.4 9743.0 9639.4 9704.3 9714.9 9717.5 9739.1 9439.2 9499.6 9501.4 9513.0 9513.3 36788.4 37181.6 37205.4 37217.0 37252.8 3422.6 3470.9 3478.9 3510.2 3538.8 26887.3 27717.0 27755.0 28038.3 28841.3 3392.5 3446.4 3521.3 3525.4 3660.6 219926.0 220743.0 221469.0 221840.0 222067.0 43936.3 47417.5 47562.8 47591.3 47628.3 48603.0 55122.0 57418.7 57481.0 62732.1 11464.0 11532.7 11559.5 11584.2 11604.8 8450.5 8512.1 8517.4 8518.8 8528.7 16697.4 16710.9 16719.0 16759.0 16803.6 6193.5 6258.2 6270.7 6282.9 6423.1 767.7 788.5 789.1 821.6 822.0 25770.0 26085.5 26158.8 26412.3 26438.2 901.1 935.6 938.1 939.8 942.1 13059.2 13809.4 13855.9 13975.7 14131.5 128245.0 128815.4 128972.0 128997.4 129127.4 47419.3 47465.3 47520.8 47551.0 47605.5 1133.5 1172.9 1178.9 1184.3 1188.9 24269.0 25079.2 26027.6 26051.7 26199.7 40793.4 41185.0 41259.0 41275.4 41290.4 265858.0 268054.0 268178.0 268258.0 268418.0 28797.8 31081.5 34422.0 35263.8 35325.1 39203.0 39260.6 39696.2 40285.2 40406.2 33976.0 34377.5 34541.8 34707.9 35094.6 32742.3 33149.2 33162.0 33775.5 33825.0 32990.0 34033.3 34436.7 34439.0 34466.5 28017.3 29291.4 30398.5 30463.5 30615.4 22897.3 23172.9 23369.2 23393.6 23525.8 08:02:44 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | [#2] jquery.html Cycles:180 Average:26202.98 Median:5117.55 stddev:73863.55 (1443.3%) stddev-sans-first:74045.22 08:02:44 INFO - PROCESS | 15637 | Values: 339.0 366.1 369.2 371.5 395.3 12446.4 12625.6 13848.8 18146.3 18249.1 18189.6 18407.8 18823.2 18958.8 19195.3 5172.1 5349.4 5377.3 5383.0 5388.4 5065.5 5190.5 5282.9 5384.8 5386.7 5169.6 5176.6 5183.8 5185.6 5195.4 5039.3 5171.4 5192.0 5193.4 5234.5 4698.3 4726.1 4729.7 4729.9 4731.7 18356.5 20329.8 21670.8 22012.8 22214.8 2086.1 2187.8 2228.0 2260.2 2552.8 9249.5 9345.3 9426.5 9650.7 9882.2 1695.9 1702.3 1710.7 1735.3 1753.2 70660.6 72506.6 72582.6 73692.0 73879.6 9739.7 9881.7 10300.2 10442.7 10718.7 13167.9 13205.7 13261.2 13264.3 13305.2 6307.2 6421.7 6520.5 6520.6 6624.3 5805.4 5840.9 5948.4 5960.2 5992.2 4187.0 4209.4 4412.6 4543.8 4672.6 1825.8 1876.2 1902.4 1930.4 1930.6 713.5 738.1 752.2 754.6 765.2 17140.6 17225.4 18085.5 18346.1 18422.7 727.1 728.1 743.2 744.6 751.3 9519.4 9832.2 9958.4 10012.7 10038.7 416446.0 435626.0 460518.0 461925.0 463184.0 80401.6 80920.0 82617.6 85460.0 87340.6 929.4 930.3 966.1 966.4 968.4 14887.3 15046.4 15564.9 15887.7 15939.8 33216.2 38237.4 38390.0 38422.6 39240.2 51809.7 51978.5 52720.2 53896.7 53979.2 7532.4 7571.8 7875.4 7890.4 8145.4 9479.7 12149.0 12178.1 12278.6 12554.1 9221.7 9255.7 9396.0 9566.0 9713.9 9808.5 9932.2 9941.4 9975.6 10241.3 10297.7 10385.6 10432.9 10820.5 10843.2 7539.2 7670.4 7735.4 7752.9 7817.5 9288.0 10363.3 10382.1 10443.5 10478.3 08:02:44 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | [#3] mootools.html Cycles:180 Average:4659.81 Median:4676.76 stddev:2546.62 (54.5%) stddev-sans-first:2539.97 08:02:44 INFO - PROCESS | 15637 | Values: 1132.3 1327.1 1336.2 1342.2 1344.8 6125.8 6897.5 7066.6 7112.4 7300.8 7188.4 7254.6 7257.3 7260.5 7261.7 1733.2 2010.9 2046.4 2049.3 2049.5 1805.4 2017.7 2083.4 2084.6 2102.5 2013.1 2014.0 2018.7 2021.7 2021.9 1972.0 2001.1 2007.8 2009.1 2009.7 1983.1 1998.1 2037.4 2040.2 2044.8 7116.1 7141.2 7171.9 7175.0 7176.0 4133.8 4293.7 4297.7 4298.6 4299.6 2555.5 2729.5 2732.8 2766.8 2778.5 1037.2 1064.7 1115.2 1125.4 1130.7 9292.8 9356.2 9360.4 9368.4 9369.0 3715.2 3963.2 3974.2 4011.3 4038.2 8206.7 8209.4 8215.7 8216.1 8222.5 4295.6 4302.5 4302.8 4304.3 4312.5 3350.3 3394.2 3886.0 3956.7 3959.2 3062.2 3335.6 3342.3 3384.6 3392.3 1089.6 1090.3 1103.1 1119.3 1123.9 780.6 784.7 795.5 796.1 802.5 4502.9 4567.5 4630.5 4633.2 4710.6 858.3 872.4 880.1 880.1 882.7 3049.8 3055.2 3099.8 3155.5 3192.1 8440.2 8579.7 8582.4 8598.1 8639.3 8605.8 8608.8 8613.0 8613.6 8616.0 6238.6 6485.7 6623.4 6790.3 6801.4 4626.2 4676.4 4677.1 4701.1 4747.1 7680.6 7681.9 7758.9 7942.6 7944.0 7743.8 7920.1 7925.3 7931.3 7975.6 5474.0 5965.8 6054.2 6066.7 6082.1 6134.6 6663.7 6874.3 6950.7 7058.9 6210.8 6401.3 6462.5 6521.8 6612.4 6284.4 6373.7 6410.6 6494.9 6515.4 5356.5 5527.9 5533.6 5533.6 5533.9 4147.8 4178.2 4234.4 4316.8 4371.3 4180.0 4297.4 4333.4 4347.5 4412.5 08:02:44 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | [#4] prototype.html Cycles:180 Average:4121.19 Median:4677.42 stddev:1165.02 (24.9%) stddev-sans-first:1133.09 08:02:44 INFO - PROCESS | 15637 | Values: 334.5 350.7 350.9 357.3 357.8 4744.3 4753.7 4796.3 4796.4 4819.2 4732.5 4745.7 4772.9 4776.9 4788.0 3598.8 3706.4 3757.5 3768.1 3790.1 3724.9 3737.7 3740.3 3758.7 3786.1 3703.1 3721.4 3725.1 3726.9 3741.4 3424.9 3734.5 3750.9 3759.6 3762.7 3116.5 3117.7 3122.1 3125.8 3150.6 4607.4 4633.3 4645.7 4650.5 4668.8 4115.2 4128.4 4130.0 4139.2 4143.0 4712.7 4937.0 4956.1 4965.4 4992.5 3572.2 3585.9 3587.5 3603.2 3611.8 4965.8 5066.8 5086.8 5237.2 5252.3 3831.2 3834.7 3923.6 3931.4 3937.1 3991.6 3994.8 3996.2 4000.7 4006.7 4013.0 4112.9 4394.1 4409.2 4425.6 4969.7 4978.7 5010.9 5042.3 5043.2 1330.8 1333.7 1340.0 1345.3 1349.2 884.5 933.9 939.7 945.8 949.0 4283.7 4285.7 4290.6 4298.8 4299.0 4932.4 4944.7 4982.2 4994.6 5017.4 4276.5 4302.4 4307.9 4312.8 4315.0 2499.0 2623.7 2637.5 2639.9 2643.5 4676.0 4710.0 4713.6 4714.3 4717.0 4899.7 5068.8 5075.5 5082.9 5111.2 4655.9 4696.8 4697.8 4698.3 4699.7 4938.0 4970.0 4973.2 4981.8 5008.8 4608.9 4860.4 4882.5 4884.3 4907.2 4608.3 4662.7 4672.8 4678.9 4742.9 3577.5 3661.4 3669.1 3700.8 3707.9 5181.2 5187.0 5188.8 5194.5 5195.7 4865.2 4995.3 5003.8 5030.3 5049.2 4905.2 4921.3 4925.4 4939.4 4969.0 4894.9 4898.0 4903.8 4908.9 4914.3 4746.8 4997.2 5002.7 5014.5 5027.5 4873.9 4875.9 4889.9 4890.6 4898.7 08:02:44 INFO - PROCESS | 15637 | 08:02:44 INFO - PROCESS | 15637 | [#5] yui.html Cycles:180 Average:13936.53 Median:25555.15 stddev:16857.99 (66.0%) stddev-sans-first:16875.79 08:02:44 INFO - PROCESS | 15637 | Values: 657.4 678.8 731.0 758.2 779.4 12499.2 13541.3 13549.6 13566.7 13569.5 12676.4 12706.0 12720.2 12723.9 12757.2 806.7 825.9 827.5 828.2 828.8 849.4 852.4 852.5 852.5 853.5 885.0 885.5 886.6 886.8 886.9 874.8 875.8 876.0 876.2 876.9 642.7 642.9 644.4 645.3 645.8 13336.9 13361.7 13449.8 13523.4 14102.2 11767.3 11811.8 12750.2 13418.5 13621.5 10606.4 10738.1 10772.9 10785.8 11018.4 3200.3 3232.5 3253.9 3340.7 3440.1 59361.7 60149.7 60683.0 60912.0 61216.1 9652.7 9731.3 9804.9 9896.7 9906.2 19095.1 19235.2 19297.0 19313.6 19318.2 6195.5 6297.0 6317.7 6549.5 6633.9 3488.7 3521.0 3523.8 3610.1 3647.5 2737.1 2784.3 2792.9 2803.5 2803.9 478.3 484.7 502.2 503.1 509.1 616.8 624.3 628.6 638.3 652.3 8255.5 8858.0 9059.8 9171.8 9584.2 570.5 591.7 601.1 613.4 617.1 4240.8 4324.9 4362.4 4404.9 4435.8 50607.2 50668.2 50856.0 58790.0 60802.4 43209.1 51083.0 54204.0 55014.0 55841.0 46787.3 47118.0 49258.0 49479.8 50917.0 8178.8 8286.9 8341.5 8439.7 8465.5 34400.5 34793.1 35738.0 36308.1 36451.8 42046.4 43931.0 44184.6 44895.8 46117.3 9786.8 9984.5 10002.6 10322.7 10370.2 10683.2 10835.2 11258.0 11436.5 11448.8 10797.8 10875.3 11050.6 11077.1 11134.1 10416.6 10794.8 10815.7 11247.4 11362.8 9998.8 10085.6 10097.5 10136.1 10141.9 8885.4 9004.3 9008.2 9067.7 9217.1 7547.8 7843.4 7923.0 8008.4 8229.3 08:02:44 INFO - PROCESS | 15637 | -------- Summary: end -------- 08:02:44 INFO - PROCESS | 15637 | 08:02:45 INFO - TEST-INFO | 15637: exit 0 08:02:45 INFO - TEST-OK | dromaeo_css | took 1124248ms 08:02:45 INFO - TEST-START | kraken 08:02:45 INFO - operating with platform_type : linux_ 08:02:45 INFO - Initialising browser for kraken test... 08:02:45 INFO - TEST-INFO | started process 15953 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqcYXl4/profile http://localhost:50209/getInfo.html) 08:02:48 INFO - PROCESS | 15953 | Iterating over profile /tmp/tmpqcYXl4/profile 08:02:49 INFO - PROCESS | 15953 | __metrics Screen width/height:1600/1200 08:02:49 INFO - PROCESS | 15953 | colorDepth:24 08:02:49 INFO - PROCESS | 15953 | Browser inner width/height: 1024/697 08:02:49 INFO - PROCESS | 15953 | __metrics 08:02:49 INFO - PROCESS | 15953 | Stat failure 08:02:49 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:49 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/frequencyCap.json = Fri Feb 17 2017 08:02:48 GMT-0800 (PST) 08:02:49 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:49 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/extensions.ini = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:49 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:49 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/bookmarkbackups = Fri Feb 17 2017 08:02:48 GMT-0800 (PST) 08:02:49 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:49 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/blocklist.xml = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:49 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:49 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/key3.db = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:49 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:49 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/cookies.sqlite = Fri Feb 17 2017 08:02:49 GMT-0800 (PST) 08:02:49 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:49 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/safebrowsing = Fri Feb 17 2017 08:02:49 GMT-0800 (PST) 08:02:49 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:49 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/extensions.json = Fri Feb 17 2017 08:02:48 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/places.sqlite-wal = Fri Feb 17 2017 08:02:49 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/cert8.db = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/directoryLinks.json = Fri Feb 17 2017 08:02:48 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/mimeTypes.rdf = Fri Feb 17 2017 08:02:49 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/cache2 = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/user.js = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/pluginreg.dat = Fri Feb 17 2017 08:02:48 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/crashes = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | Stat failure 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/permissions.sqlite = Fri Feb 17 2017 08:02:46 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/compatibility.ini = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/gmp = Fri Feb 17 2017 08:02:46 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/addons.json = Fri Feb 17 2017 08:02:48 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/localstore.rdf = Fri Feb 17 2017 07:43:21 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/.parentlock = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/thumbnails = Fri Feb 17 2017 08:02:48 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/places.sqlite = Fri Feb 17 2017 08:02:49 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/secmod.db = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/minidumps = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/content-prefs.sqlite = Fri Feb 17 2017 08:02:47 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/places.sqlite-shm = Fri Feb 17 2017 08:02:47 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/prefs.js = Fri Feb 17 2017 07:43:21 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/startupCache = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/sessionCheckpoints.json = Fri Feb 17 2017 08:02:49 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/extensions = Fri Feb 17 2017 08:02:45 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | No birth date. Using mtime. 08:02:50 INFO - PROCESS | 15953 | Using date: /tmp/tmpqcYXl4/profile/webappsstore.sqlite = Fri Feb 17 2017 08:02:49 GMT-0800 (PST) 08:02:50 INFO - PROCESS | 15953 | 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:02:50 INFO - PROCESS | 15953 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:02:52 INFO - TEST-INFO | 15953: exit 0 08:02:52 INFO - Browser initialized. 08:02:52 INFO - Running cycle 1/1 for kraken test... 08:02:52 INFO - TEST-INFO | started process 16041 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqcYXl4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:04 INFO - PROCESS | 16041 | RSS: Main: 147705856 08:03:04 INFO - PROCESS | 16041 | 08:03:07 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50209/tests/kraken/driver.html?testName=audio-beat-detection) 08:03:07 INFO - PROCESS | 16041 | RSS: Main: 202788864 08:03:07 INFO - PROCESS | 16041 | 08:03:10 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50209/tests/kraken/driver.html?testName=audio-dft) 08:03:11 INFO - PROCESS | 16041 | RSS: Main: 181563392 08:03:11 INFO - PROCESS | 16041 | 08:03:14 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50209/tests/kraken/driver.html?testName=audio-fft) 08:03:15 INFO - PROCESS | 16041 | RSS: Main: 263524352 08:03:15 INFO - PROCESS | 16041 | 08:03:17 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50209/tests/kraken/driver.html?testName=audio-oscillator) 08:03:17 INFO - PROCESS | 16041 | RSS: Main: 175001600 08:03:17 INFO - PROCESS | 16041 | 08:03:20 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50209/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:03:20 INFO - PROCESS | 16041 | RSS: Main: 178593792 08:03:20 INFO - PROCESS | 16041 | 08:03:23 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50209/tests/kraken/driver.html?testName=imaging-darkroom) 08:03:24 INFO - PROCESS | 16041 | RSS: Main: 217894912 08:03:24 INFO - PROCESS | 16041 | 08:03:27 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50209/tests/kraken/driver.html?testName=imaging-desaturate) 08:03:28 INFO - PROCESS | 16041 | RSS: Main: 212021248 08:03:28 INFO - PROCESS | 16041 | 08:03:31 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50209/tests/kraken/driver.html?testName=json-parse-financial) 08:03:31 INFO - PROCESS | 16041 | RSS: Main: 213499904 08:03:31 INFO - PROCESS | 16041 | 08:03:33 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50209/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:03:34 INFO - PROCESS | 16041 | RSS: Main: 176955392 08:03:34 INFO - PROCESS | 16041 | 08:03:36 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50209/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:03:36 INFO - PROCESS | 16041 | RSS: Main: 184287232 08:03:36 INFO - PROCESS | 16041 | 08:03:39 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50209/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:03:39 INFO - PROCESS | 16041 | RSS: Main: 192921600 08:03:39 INFO - PROCESS | 16041 | 08:03:42 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50209/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:03:43 INFO - PROCESS | 16041 | RSS: Main: 202657792 08:03:43 INFO - PROCESS | 16041 | 08:03:46 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50209/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:03:46 INFO - PROCESS | 16041 | RSS: Main: 199077888 08:03:46 INFO - PROCESS | 16041 | 08:03:48 INFO - PROCESS | 16041 | Cycle 1(1): loaded http://localhost:50209/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50209/tests/kraken/driver.html?testName=ai-astar) 08:03:49 INFO - PROCESS | 16041 | RSS: Main: 250662912 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | __start_tp_report 08:03:49 INFO - PROCESS | 16041 | _x_x_mozilla_page_load 08:03:49 INFO - PROCESS | 16041 | _x_x_mozilla_page_load_details 08:03:49 INFO - PROCESS | 16041 | |i|pagename|runs| 08:03:49 INFO - PROCESS | 16041 | |0;ai-astar;114;116;115;115;115;116;115;115;115;114 08:03:49 INFO - PROCESS | 16041 | |1;audio-beat-detection;157;125;129;163;128;128;127;125;149;126 08:03:49 INFO - PROCESS | 16041 | |2;audio-dft;163;161;163;164;163;161;162;161;162;161 08:03:49 INFO - PROCESS | 16041 | |3;audio-fft;76;78;78;109;79;78;77;77;104;77 08:03:49 INFO - PROCESS | 16041 | |4;audio-oscillator;85;86;108;84;83;84;101;85;84;102 08:03:49 INFO - PROCESS | 16041 | |5;imaging-gaussian-blur;114;113;114;114;114;113;113;114;113;113 08:03:49 INFO - PROCESS | 16041 | |6;imaging-darkroom;149;149;149;149;149;150;150;150;149;150 08:03:49 INFO - PROCESS | 16041 | |7;imaging-desaturate;95;96;96;95;95;96;95;96;96;95 08:03:49 INFO - PROCESS | 16041 | |8;json-parse-financial;62;62;61;60;62;69;60;62;62;66 08:03:49 INFO - PROCESS | 16041 | |9;json-stringify-tinderbox;66;67;67;66;66;66;66;65;66;66 08:03:49 INFO - PROCESS | 16041 | |10;stanford-crypto-aes;89;89;92;88;88;88;97;88;88;88 08:03:49 INFO - PROCESS | 16041 | |11;stanford-crypto-ccm;150;137;147;137;144;136;136;135;176;139 08:03:49 INFO - PROCESS | 16041 | |12;stanford-crypto-pbkdf2;185;184;184;186;181;183;183;182;181;182 08:03:49 INFO - PROCESS | 16041 | |13;stanford-crypto-sha256-iterative;72;71;70;71;71;71;70;71;69;69 08:03:49 INFO - PROCESS | 16041 | __end_tp_report 08:03:49 INFO - PROCESS | 16041 | __start_cc_report 08:03:49 INFO - PROCESS | 16041 | _x_x_mozilla_cycle_collect,2031 08:03:49 INFO - PROCESS | 16041 | __end_cc_report 08:03:49 INFO - PROCESS | 16041 | __startTimestamp1487347429028__endTimestamp 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | ------- Summary: start ------- 08:03:49 INFO - PROCESS | 16041 | Number of tests: 14 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 08:03:49 INFO - PROCESS | 16041 | Values: 114.0 116.0 115.0 115.0 115.0 116.0 115.0 115.0 115.0 114.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:128.50 stddev:14.67 (11.4%) stddev-sans-first:13.39 08:03:49 INFO - PROCESS | 16041 | Values: 157.0 125.0 129.0 163.0 128.0 128.0 127.0 125.0 149.0 126.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.50 stddev:1.10 (0.7%) stddev-sans-first:1.12 08:03:49 INFO - PROCESS | 16041 | Values: 163.0 161.0 163.0 164.0 163.0 161.0 162.0 161.0 162.0 161.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#3] audio-fft Cycles:10 Average:83.30 Median:77.50 stddev:12.31 (15.9%) stddev-sans-first:12.77 08:03:49 INFO - PROCESS | 16041 | Values: 76.0 78.0 78.0 109.0 79.0 78.0 77.0 77.0 104.0 77.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:84.00 stddev:9.50 (11.3%) stddev-sans-first:9.88 08:03:49 INFO - PROCESS | 16041 | Values: 85.0 86.0 108.0 84.0 83.0 84.0 101.0 85.0 84.0 102.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:03:49 INFO - PROCESS | 16041 | Values: 114.0 113.0 114.0 114.0 114.0 113.0 113.0 114.0 113.0 113.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:149.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 08:03:49 INFO - PROCESS | 16041 | Values: 149.0 149.0 149.0 149.0 149.0 150.0 150.0 150.0 149.0 150.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:03:49 INFO - PROCESS | 16041 | Values: 95.0 96.0 96.0 95.0 95.0 96.0 95.0 96.0 96.0 95.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:62.00 stddev:2.80 (4.5%) stddev-sans-first:2.96 08:03:49 INFO - PROCESS | 16041 | Values: 62.0 62.0 61.0 60.0 62.0 69.0 60.0 62.0 62.0 66.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 08:03:49 INFO - PROCESS | 16041 | Values: 66.0 67.0 67.0 66.0 66.0 66.0 66.0 65.0 66.0 66.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:88.50 stddev:2.92 (3.3%) stddev-sans-first:3.09 08:03:49 INFO - PROCESS | 16041 | Values: 89.0 89.0 92.0 88.0 88.0 88.0 97.0 88.0 88.0 88.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:141.50 stddev:12.47 (8.8%) stddev-sans-first:13.02 08:03:49 INFO - PROCESS | 16041 | Values: 150.0 137.0 147.0 137.0 144.0 136.0 136.0 135.0 176.0 139.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.10 Median:183.50 stddev:1.66 (0.9%) stddev-sans-first:1.62 08:03:49 INFO - PROCESS | 16041 | Values: 185.0 184.0 184.0 186.0 181.0 183.0 183.0 182.0 181.0 182.0 08:03:49 INFO - PROCESS | 16041 | 08:03:49 INFO - PROCESS | 16041 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:71.00 stddev:0.97 (1.4%) stddev-sans-first:0.87 08:03:49 INFO - PROCESS | 16041 | Values: 72.0 71.0 70.0 71.0 71.0 71.0 70.0 71.0 69.0 69.0 08:03:49 INFO - PROCESS | 16041 | -------- Summary: end -------- 08:03:49 INFO - PROCESS | 16041 | 08:03:50 INFO - TEST-INFO | 16041: exit 0 08:03:50 INFO - TEST-OK | kraken | took 64964ms 08:03:50 INFO - SUITE-END | took 1189s 08:03:50 INFO - Completed test suite (00:19:49) 08:03:50 INFO - javascript benchmark 08:03:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6161.299615039057, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2548.7068987066486, 2572.8584927828983, 2578.79369650428, 2604.544204764166, 2610.034287313694, 4535.705179163352, 4574.908501829969, 4642.35007099006, 4659.906801863961, 4674.158651442739, 4589.839355622554, 4633.559811817872, 4645.233536466488, 4665.790039448225, 4683.852456148248, 4237.216115018724, 4278.272693642089, 4291.742495450283, 4352.238358287303, 4374.60628543431, 4370.2352088384405, 4405.779711014453, 4417.138657961683, 4419.403380543634, 4446.955530444703, 4194.3918131870705, 4219.641330486912, 4236.0, 4271.444712187426, 4380.3210502372185, 4255.87232383029, 4283.635890949273, 4325.82696692132, 4334.934975975363, 4381.342798580238, 4331.848385306499, 4340.348947657845, 4360.869173924755, 4384.386185933972, 4390.407295015164, 4660.603848672867, 4709.434867815849, 4715.905681886377, 4733.479317275097, 4769.046190761862, 4802.06359759847, 4825.179719447668, 4832.251001094836, 5066.189409694432, 5072.898542029176, 4913.557779799835, 4927.359443272388, 4928.359313289249, 4928.507149285078, 4933.210686290176, 4627.930581041287, 4663.836765713184, 4692.81228750853, 4709.882252943636, 4769.833055843098, 5154.690718556823, 5155.149400348975, 5208.5312321891215, 5211.609129315317, 5214.374275086938, 4654.418197725285, 4733.455652599926, 4734.668573199843, 4799.760011999386, 4910.312556242129, 4872.342233798392, 4877.561019508261, 4880.487548807395, 4881.804727810919, 4884.975575122173, 4835.323054772335, 4840.75796210188, 4847.660663753503, 4993.051320249214, 5013.573846223039, 5052.646314758005, 5054.039732450822, 5065.924011139836, 5066.797328106834, 5103.464136265713, 4161.0, 4163.604457576525, 4175.248455278019, 4189.4344263524035, 4208.85269015583, 3660.0666829958723, 3689.114612493036, 3693.076730817296, 3704.5554533456157, 3710.276496083292, 4312.633426158749, 4318.611324980767, 4319.568043195655, 4323.610875021263, 4363.3018717005125, 4726.456457507431, 4738.336632871401, 4742.786574604151, 4969.9563091750415, 5003.974980125076, 4195.559466255999, 4219.51475580306, 4243.469566304212, 4367.148406060787, 4424.093060922503, 4626.190416677068, 4656.953430465652, 4657.790399432034, 4662.557057079572, 4665.836695715634, 4851.975740121276, 4857.635677324144, 4862.294967229661, 4864.416270047617, 4909.288153217834, 4914.0908931847725, 5090.185570308733, 5138.563222126161, 5144.536991670618, 5195.038917800219, 4693.671442998957, 4764.475907650225, 4789.640776941674, 4827.8551643450755, 4895.681780684244, 5034.572061374824, 5044.848654540369, 5047.318611987482, 5088.542031217208, 5090.160123579568, 5108.489151084862, 5120.25756265332, 5121.0, 5121.436641969306, 5126.102931986962, 4674.0, 4755.310479980452, 4833.202521583899, 4974.427940786856, 4982.526659967442, 4804.711717296974, 4914.631402644887, 4923.261510773413, 4930.087933732271, 4939.160342741814, 4829.903401932012, 4837.322774811459, 4840.177169881199, 4854.05345957549, 4887.0, 4899.975500122477, 4913.754312284443, 4918.778654960463, 4921.876953076202, 4981.402231732215, 4575.3365761965, 4599.747013914267, 4603.217453032926, 4815.95184048155, 4830.420349558076], "value": 4595.235120776707, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.3148580017878, 554.2268535393126, 577.0622738050665, 580.9506191973682, 602.5601311042939, 27221.319467013334, 27776.0, 39040.41439378408, 40359.79820100903, 40660.0, 41015.38476922848, 41441.0, 41467.0, 41486.792566037206, 41500.58499415005, 7449.627518624061, 7678.961605191994, 7686.807829804272, 7695.038120234971, 7697.653605587734, 6738.12404387429, 6888.0, 9344.626214951393, 9561.043895610454, 9590.520473976274, 9680.273979451535, 9725.076117768836, 9733.513324333755, 9741.415515069084, 9743.0, 9639.373440726366, 9704.320697551175, 9714.854277185848, 9717.514124293792, 9739.123478886933, 9439.150476457151, 9499.620015199383, 9501.4299142051, 9513.001134880808, 9513.33406661534, 36788.44817327742, 37181.62818371809, 37205.441918371245, 37217.0, 37252.81373593142, 3422.606400263952, 3470.9132271693156, 3478.9478157827652, 3510.157562185057, 3538.840752166133, 26887.32781680454, 27717.0, 27755.0, 28038.31770093801, 28841.278968025756, 3392.4911263310455, 3446.3968805458944, 3521.3485505181366, 3525.435930251173, 3660.633936606345, 219926.0, 220743.0, 221469.0, 221840.0, 222066.99999999837, 43936.3409548857, 47417.525824742, 47562.76218618954, 47591.28613070807, 47628.28557571639, 48603.0, 55122.0, 57418.712906436034, 57481.0, 62732.09946905837, 11463.993801084776, 11532.654020379403, 11559.537618495166, 11584.189106762446, 11604.825927611093, 8450.492970421794, 8512.106228846005, 8517.403781735344, 8518.82962340762, 8528.74413767588, 16697.41374569414, 16710.916445417693, 16719.0, 16759.0, 16803.579910502092, 6193.504519638418, 6258.217722784652, 6270.717817698214, 6282.874342513215, 6423.100765892778, 767.6583920155477, 788.4953629677011, 789.1398375770323, 821.5768879027306, 821.954792486407, 25770.0, 26085.478290434086, 26158.80031256374, 26412.33969150748, 26438.206853794418, 901.0718959471754, 935.5836652689488, 938.1369140390857, 939.769756409684, 942.0673533202217, 13059.21644701321, 13809.447622095004, 13855.930720346334, 13975.650608734659, 14131.505397311044, 128245.0, 128815.35592322166, 128972.0, 128997.35501322434, 129127.35436322758, 47419.28871066937, 47465.28802067972, 47520.76239618849, 47551.0, 47605.52394476011, 1133.4955944604737, 1172.941352932367, 1178.935158566287, 1184.3071802995173, 1188.851393575803, 24269.0, 25079.24762257135, 26027.609585855847, 26051.739482605688, 26199.738002620492, 40793.388099177944, 41185.0, 41259.0, 41275.38086928758, 41290.38064429096, 265858.0, 268054.0, 268178.0, 268258.0, 268418.0, 28797.848230517935, 31081.53377699291, 34422.0, 35263.82368088143, 35325.116872078404, 39203.0, 39260.60739392684, 39696.22885825842, 40285.1942961145, 40406.19187616173, 33976.0, 34377.48433773445, 34541.82729086339, 34707.917524948585, 35094.649053509136, 32742.34515309728, 33149.17127071843, 33162.0, 33775.49336759902, 33825.0, 32990.0, 34033.31933361269, 34436.65563344335, 34439.0, 34466.483002754474, 28017.299567510974, 29291.41417171602, 30398.54402184013, 30463.543046854757, 30615.387692246477, 22897.313080607608, 23172.884135579214, 23369.182078627167, 23393.649095263245, 23525.764742352356], "value": 16519.86748035756, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.9762915993701, 366.1286138989207, 369.21173295015143, 371.48549259275893, 395.3299157927314, 12446.377681115942, 12625.621231363062, 13848.792268115974, 18146.274149034056, 18249.0875456227, 18189.636207275846, 18407.815921840778, 18823.247070117213, 18958.810411895876, 19195.32816351428, 5172.146595811687, 5349.411564727877, 5377.327834020747, 5383.0, 5388.407275199725, 5065.544101030907, 5190.480951904817, 5282.867928301785, 5384.757685904124, 5386.73066346683, 5169.56058735008, 5176.55999240065, 5183.818566350178, 5185.585153187755, 5195.40252870919, 5039.3196918415915, 5171.4311425743135, 5192.013517431695, 5193.376794784613, 5234.476552344735, 4698.318743782167, 4726.125666751662, 4729.668923175379, 4729.929051064237, 4731.716097034155, 18356.54108647281, 20329.796702032938, 21670.78329216719, 22012.779872201234, 22214.777852221432, 2086.1446806809136, 2187.814035806958, 2227.988860055689, 2260.2315212827716, 2552.7574880386333, 9249.491277979709, 9345.345825792198, 9426.528673566294, 9650.71047868565, 9882.209423246195, 1695.9406420775338, 1702.3445973300245, 1710.7471153417796, 1735.3405705831829, 1753.1672455583623, 70660.64669676671, 72506.63746681233, 72582.63708681475, 73692.0, 73879.63060184718, 9739.659111931049, 9881.677805715652, 10300.175985921258, 10442.738931526772, 10718.73203169927, 13167.934160329138, 13205.669858253621, 13261.204327740366, 13264.336783160803, 13305.201687898758, 6307.180066591313, 6421.711023003976, 6520.543561950618, 6520.608763474207, 6624.337566243337, 5805.390434004453, 5840.912386314209, 5948.375420580864, 5960.195373624592, 5992.191054207713, 4186.979065104716, 4209.368594710757, 4412.646988240933, 4543.818247270138, 4672.649551283667, 1825.8174182581902, 1876.1651065276094, 1902.353199912009, 1930.420873737873, 1930.6331796958814, 713.5362014690493, 738.0774032459426, 752.1838804896748, 754.607604045893, 765.2385876053363, 17140.634195485443, 17225.39711110105, 18085.547861303574, 18346.082695865152, 18422.723659145122, 727.0911360799001, 728.0862517540453, 743.1565173528085, 744.6388501576731, 751.2975368030909, 9519.381240219363, 9832.213422926148, 9958.402495850272, 10012.69961901144, 10038.749031274276, 416446.0, 435626.0, 460518.0, 461925.0, 463184.0, 80401.59799201084, 80920.0, 82617.58691206506, 85460.0, 87340.56329718311, 929.3726734454351, 930.2697382554662, 966.1401352796017, 966.40566051879, 968.4479846487575, 14887.255637218312, 15046.400127588171, 15564.92217538905, 15887.68224635524, 15939.840601593836, 33216.1695957603, 38237.42643860289, 38390.0, 38422.615773841906, 39240.20894254103, 51809.740951295, 51978.48021519736, 52720.209196862845, 53896.73051634717, 53979.173019912676, 7532.43506736986, 7571.848563028819, 7875.409344299315, 7890.447668663139, 8145.42981991255, 9479.715608531755, 12149.028077753936, 12178.147529672839, 12278.631641050782, 12554.121211515107, 9221.677241296522, 9255.722328330161, 9396.0, 9565.952170239105, 9713.854292185482, 9808.460534670376, 9932.155766759617, 9941.403515789076, 9975.551100200364, 10241.283110182216, 10297.74255643615, 10385.584576616851, 10432.895671043496, 10820.458977051274, 10843.240973131806, 7539.208383119693, 7670.386369090571, 7735.419843511647, 7752.883706744291, 7817.452778305692, 9288.0, 10363.32638378503, 10382.065614094465, 10443.477826108816, 10478.273839117215], "value": 8165.73102397802, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1132.3319241647425, 1327.130729372261, 1336.1718675993282, 1342.201390172847, 1344.7579435701575, 6125.754969801208, 6897.482688798342, 7066.6113363765035, 7112.395446387063, 7300.817479563014, 7188.388986936116, 7254.564726116437, 7257.310555497219, 7260.528065675728, 7261.673224704888, 1733.159417682423, 2010.9296174633891, 2046.4167712202025, 2049.313479984207, 2049.4568939231117, 1805.4493379519242, 2017.6872584749315, 2083.4166433398673, 2084.593504266669, 2102.4743814046487, 2013.1242909334487, 2014.033264033265, 2018.6669199582052, 2021.7169596256535, 2021.9191232350688, 1972.0435588739522, 2001.0895042755512, 2007.8293345065613, 2009.0657844102416, 2009.678451447776, 1983.1274239334646, 1998.1008546154144, 2037.4397040813747, 2040.1941233212797, 2044.8159665630164, 7116.110486189226, 7141.178764442052, 7171.856562868714, 7174.964125179393, 7176.0, 4133.793310334501, 4293.720908140992, 4297.699161058727, 4298.591633794785, 4299.591538803808, 2555.4505781256935, 2729.467753788012, 2732.8223665461683, 2766.8478233697138, 2778.499870023395, 1037.2428127466992, 1064.6646306413454, 1115.2249186815168, 1125.401929260452, 1130.7229728716518, 9292.767680808034, 9356.204722598588, 9360.391574547633, 9368.39105458143, 9369.016253293374, 3715.1826598148364, 3963.227170701743, 3974.2448934702315, 4011.2579172853116, 4038.2327357801923, 8206.712765053315, 8209.38429617781, 8215.671373145127, 8216.137305582948, 8222.547759873145, 4295.591918767775, 4302.526722060571, 4302.763348015889, 4304.268274393361, 4312.460942382202, 3350.329934013207, 3394.2193295542165, 3885.9805700971315, 3956.742811717229, 3959.2279505496726, 3062.2344413896526, 3335.5657067461225, 3342.298117395326, 3384.5600071990484, 3392.3045775615938, 1089.5805115030612, 1090.3022065877844, 1103.0679076180616, 1119.2948442481347, 1123.915706322029, 780.5667854340782, 784.6861255497846, 795.5385876191795, 796.0964305513285, 802.450321529754, 4502.932456013163, 4567.520410356865, 4630.536946305342, 4633.166030114612, 4710.552497512731, 858.2618947704968, 872.437277955715, 880.0847118996172, 880.1286726141202, 882.7042940614838, 3049.771267154972, 3055.220918665726, 3099.783015188915, 3155.5108958111527, 3192.1221664042573, 8440.240378365976, 8579.656813727506, 8582.356323275779, 8598.05421403645, 8639.308855291561, 8605.784855378666, 8608.827823443497, 8613.0, 8613.569321533898, 8615.956920215358, 6238.594491358047, 6485.675716214265, 6623.4038936495945, 6790.287019862942, 6801.387875091265, 4626.213543697647, 4676.415448068991, 4677.111348843709, 4701.119552179019, 4747.050589881968, 7680.615969201406, 7681.923180768121, 7758.883616745867, 7942.563159026078, 7943.960280198562, 7743.7676869694, 7920.128785833669, 7925.326347260317, 7931.325837303893, 7975.5613441261285, 5474.0, 5965.761369545169, 6054.212952316171, 6066.726997285221, 6082.087686847009, 6134.601250918676, 6663.666816659244, 6874.312568743086, 6950.721971121098, 7058.858822823618, 6210.7515699371515, 6401.295857455583, 6462.483001359974, 6521.771737989147, 6612.404883560503, 6284.371562843679, 6373.745050198126, 6410.551261411656, 6494.9025764612625, 6515.413612774873, 5356.4911333424825, 5527.861803454945, 5533.557315414838, 5533.612647114664, 5533.944660553343, 4147.81334839939, 4178.22702799983, 4234.42835217254, 4316.784160791885, 4371.256886329268, 4179.97910010448, 4297.441332626739, 4333.436653235185, 4347.521772605074, 4412.492563355127], "value": 3805.425003841448, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.5015926269859, 350.7176722738198, 350.85965613754496, 357.25334051831675, 357.8121486219733, 4744.33579298898, 4753.691010084342, 4796.273341597022, 4796.352492413524, 4819.180739274323, 4732.46194986629, 4745.738984355859, 4772.947632518016, 4776.928346074812, 4787.976060119695, 3598.7660802047913, 3706.369917114084, 3757.530308711411, 3768.0768211788013, 3790.1093243087853, 3724.944125838115, 3737.682297004758, 3740.2706472237924, 3758.6617204451595, 3786.110264087937, 3703.1112532992024, 3721.441783732448, 3725.1245957200035, 3726.888193354204, 3741.4387841823673, 3424.9486257706153, 3734.477173195755, 3750.8559889233775, 3759.6428339307995, 3762.698984081267, 3116.5481005254337, 3117.703818137273, 3122.1414111119398, 3125.796823206484, 3150.6061742282213, 4607.378003969488, 4633.32816741574, 4645.698029628097, 4650.511696271877, 4668.7589956820575, 4115.156392939441, 4128.3784796654045, 4129.97935010326, 4139.212620452283, 4142.958570414287, 4712.740799256074, 4936.950630493685, 4956.08312462192, 4965.361430727888, 4992.450830408652, 3572.214112895159, 3585.8744943926836, 3587.4798154267487, 3603.1712706077487, 3611.7652352596997, 4965.776540055706, 5066.771995260186, 5086.821961231339, 5237.188235823453, 5252.290940722992, 3831.1954489556947, 3834.7123965702685, 3923.6076392360537, 3931.3513270310077, 3937.0944682723134, 3991.6207960243732, 3994.78028708418, 3996.200759848042, 4000.6599439047427, 4006.719529632898, 4013.0451061146377, 4112.856050038235, 4394.0666106913495, 4409.162259170747, 4425.601695847325, 4969.701817890938, 4978.726170060681, 5010.897602527438, 5042.319286896295, 5043.218301163325, 1330.7671157547584, 1333.6932505523785, 1339.9598012059653, 1345.3049158060915, 1349.1702602899147, 884.5135175653365, 933.9066093390607, 939.7227817793768, 945.7588314979636, 948.9525523723786, 4283.700140990163, 4285.742855428684, 4290.570942905684, 4298.849540266076, 4299.022449673185, 4932.432770231398, 4944.703317800943, 4982.202847544448, 4994.575461085776, 5017.422996355393, 4276.508201556799, 4302.44068271129, 4307.892302692395, 4312.849050283225, 4314.956850431518, 2498.9750102498742, 2623.6851577810785, 2637.5252454558363, 2639.9076032338776, 2643.5109504741686, 4675.976620116946, 4709.976450117728, 4713.599344055725, 4714.339992401066, 4716.976415117902, 4899.681520701143, 5068.82259120929, 5075.543201111917, 5082.898342033139, 5111.236623364933, 4655.906881862344, 4696.788644511005, 4697.835575754901, 4698.345434829311, 4699.718016918996, 4937.975310123426, 4969.981153863377, 4973.229149481836, 4981.825636102719, 5008.774605142705, 4608.861734147981, 4860.441049279237, 4882.511748825089, 4884.267359896044, 4907.233395977517, 4608.28571571407, 4662.7202367858035, 4672.785075880363, 4678.8596342109795, 4742.928856067231, 3577.5349204603235, 3661.4324779659196, 3669.119411341312, 3700.8149592520804, 3707.9073023174637, 5181.171012637959, 5187.0, 5188.818391356284, 5194.53249207558, 5195.74021298926, 4865.221564549655, 4995.275685025578, 5003.849884503471, 5030.346055012825, 5049.2426136079885, 4905.23968784839, 4921.26181072842, 4925.433575138905, 4939.43196532389, 4968.950310496849, 4894.877628059327, 4898.0, 4903.754812259444, 4908.901821963612, 4914.311996320589, 4746.762661866962, 4997.20044792846, 5002.723216293333, 5014.548690617716, 5027.497250274943, 4873.853784386474, 4875.926861097158, 4889.926651100165, 4890.584300334369, 4898.657094003385], "value": 3772.56867304313, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.4313219065509, 678.7510979797172, 730.987582198655, 758.2228216078519, 779.4076501858585, 12499.18755280906, 13541.255230962293, 13549.593512194637, 13566.728665426686, 13569.45722171112, 12676.366181690924, 12706.047046471505, 12720.236785792837, 12723.872761272385, 12757.234565926028, 806.6612022950375, 825.9297959673435, 827.5117680568463, 828.1676914700718, 828.772087675888, 849.3799526345771, 852.4459101583989, 852.4927668037539, 852.5481494807731, 853.4793775796759, 884.9867251991225, 885.4864178776327, 886.57887503436, 886.7605746448455, 886.8980067292247, 874.7987962768535, 875.8379699755534, 875.951822649754, 876.1939016105199, 876.912308769118, 642.7204166187677, 642.9421352078335, 644.4296797334392, 645.2643985856128, 645.8449972006731, 13336.933315333457, 13361.73276534464, 13449.798253026114, 13523.39144738489, 14102.224377659224, 11767.28192010828, 11811.822822657667, 12750.234985900875, 13418.530351437652, 13621.455141794415, 10606.416647084407, 10738.08726258269, 10772.892271077268, 10785.83821242682, 11018.44907754617, 3200.3279311344645, 3232.4828027515487, 3253.9349213015844, 3340.749443791701, 3440.088376580208, 59361.7031914842, 60149.6992515039, 60683.0, 60912.0, 61216.08070137128, 9652.710418687451, 9731.318807683536, 9804.948356947923, 9896.65361712351, 9906.157976571927, 19095.14071866769, 19235.230590776213, 19297.0, 19313.613727725366, 19318.227270909007, 6195.535334849904, 6297.0, 6317.747290108436, 6549.508786841006, 6633.933660663428, 3488.66857648523, 3520.982395088008, 3523.788572685647, 3610.097475631092, 3647.544056992876, 2737.110439107298, 2784.331760377495, 2792.888284468639, 2803.481355949156, 2803.8598070096414, 478.2563114357208, 484.71644088208086, 502.1714171616861, 503.0668110654683, 509.1344713986238, 616.8457885528618, 624.2820756130486, 628.5788521690479, 638.3425072175667, 652.2824892618177, 8255.463394879314, 8858.0, 9059.773505662411, 9171.770705732277, 9584.233261339215, 570.5179123640518, 591.6952769323801, 601.1223613524304, 613.365167052099, 617.0713076819447, 4240.830366785294, 4324.935125973113, 4362.389265502832, 4404.911901761947, 4435.822567097344, 50607.240891385925, 50668.239976400386, 50856.0, 58790.0, 60802.39197608056, 43209.13581728348, 51082.97834043373, 54204.0, 55014.0, 55841.0, 46787.298190527166, 47118.0, 49258.0, 49479.75260123676, 50917.0, 8178.7955301117945, 8286.917130828615, 8341.541215233221, 8439.746807595782, 8465.492070475791, 34400.48399274063, 34793.130171744895, 35738.0344081078, 36308.09229769277, 36451.81774091112, 42046.36930446107, 43931.0, 44184.558154418046, 44895.77552112219, 46117.30824037575, 9786.75533111678, 9984.450855203033, 10002.599896004078, 10322.741931451774, 10370.222233332379, 10683.198760092868, 10835.187360947803, 11258.043066339453, 11436.542538298707, 11448.82826757616, 10797.784044319227, 10875.347479151276, 11050.557977680803, 11077.058450031838, 11134.109271258441, 10416.583336666448, 10794.838077428687, 10815.729606759895, 11247.437628118398, 11362.829557556808, 9998.800023999625, 10085.58065479158, 10097.495125243855, 10136.087752102347, 10141.898581014095, 8885.42244754089, 9004.279657627243, 9008.189262966365, 9067.68263110788, 9217.078292170998, 7547.849043019219, 7843.372530197685, 7922.960385198037, 8008.359331253602, 8229.300509456763], "value": 6146.577168993254, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1560.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 115.0, 115.0, 115.0, 116.0, 115.0, 115.0, 115.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 125.0, 129.0, 163.0, 128.0, 128.0, 127.0, 125.0, 149.0, 126.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 163.0, 164.0, 163.0, 161.0, 162.0, 161.0, 162.0, 161.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 78.0, 109.0, 79.0, 78.0, 77.0, 77.0, 104.0, 77.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 108.0, 84.0, 83.0, 84.0, 101.0, 85.0, 84.0, 102.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 150.0, 150.0, 150.0, 149.0, 150.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 60.0, 62.0, 69.0, 60.0, 62.0, 62.0, 66.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 92.0, 88.0, 88.0, 88.0, 97.0, 88.0, 88.0, 88.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 137.0, 147.0, 137.0, 144.0, 136.0, 136.0, 135.0, 176.0, 139.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 186.0, 181.0, 183.0, 183.0, 182.0, 181.0, 182.0], "value": 183.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 70.0, 71.0, 71.0, 71.0, 70.0, 71.0, 69.0, 69.0], "value": 70.5, "unit": "score"}]}]} 08:03:50 INFO - Return code: 0 08:03:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:03:50 ERROR - # TBPL SUCCESS # 08:03:50 INFO - Running post-action listener: _resource_record_post_action 08:03:50 INFO - [mozharness: 2017-02-17 16:03:50.825162Z] Finished run-tests step (success) 08:03:50 INFO - Running post-run listener: _resource_record_post_run 08:03:50 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 357588992; Read time: 16; Write time: 315172 08:03:50 INFO - TinderboxPrint: CPU usage
13.2% 08:03:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 08:03:50 INFO - TinderboxPrint: I/O write bytes / time
357,588,992 / 315,172 08:03:50 INFO - TinderboxPrint: CPU idle
8,351.2 (86.8%) 08:03:50 INFO - TinderboxPrint: CPU user
1,218.4 (12.7%) 08:03:50 INFO - TinderboxPrint: Swap in / out
0 / 0 08:03:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105361408; Read time: 0; Write time: 128128 08:03:50 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 252227584; Read time: 0; Write time: 187044 08:03:51 INFO - Running post-run listener: _upload_blobber_files 08:03:51 INFO - Blob upload gear active. 08:03:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:03:51 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:03:51 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:03:51 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:03:51 INFO - (blobuploader) - INFO - Open directory for files ... 08:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:03:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:03:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:52 INFO - (blobuploader) - INFO - Done attempting. 08:03:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:03:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:03:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:58 INFO - (blobuploader) - INFO - Done attempting. 08:03:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:03:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:03:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:59 INFO - (blobuploader) - INFO - Done attempting. 08:03:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:03:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:04:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:04:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:04:00 INFO - (blobuploader) - INFO - Done attempting. 08:04:00 INFO - (blobuploader) - INFO - Iteration through files over. 08:04:00 INFO - Return code: 0 08:04:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:04:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:04:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34aa5f3d520ff6daea8cdce450484226701a3b5f32d1c4de153ff21fc07feba59156ccbcc548c06f92f477fc5c470660354fcd9e636653864dfa1345f0baa723", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/07f3931d8fe1546967f7e794cb3c7105fed3e4c4735cc75cb39bfb58d605b7e234594aa1fdd6d905c7ee623440992cdcb8a9b547327b8e637ad8ecec6e8c4f57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2126264a48a0aae1f13290caae168c62873c5fff811e9ac072117fbef8e412ef69f908cb112c239cb6e544f39a770ee11085c04f9524cfc6a25857757750327a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9a28fb0a5d69c2d1cb64cd7034b1461aba45e835b7b87570cdcb337ac8d8a8824b73a6ab9a8334663ceca4483c7e4d73d0edffbcd2b99e74505472a10ff03a86"} 08:04:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:04:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:04:00 INFO - Contents: 08:04:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34aa5f3d520ff6daea8cdce450484226701a3b5f32d1c4de153ff21fc07feba59156ccbcc548c06f92f477fc5c470660354fcd9e636653864dfa1345f0baa723", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/07f3931d8fe1546967f7e794cb3c7105fed3e4c4735cc75cb39bfb58d605b7e234594aa1fdd6d905c7ee623440992cdcb8a9b547327b8e637ad8ecec6e8c4f57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2126264a48a0aae1f13290caae168c62873c5fff811e9ac072117fbef8e412ef69f908cb112c239cb6e544f39a770ee11085c04f9524cfc6a25857757750327a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9a28fb0a5d69c2d1cb64cd7034b1461aba45e835b7b87570cdcb337ac8d8a8824b73a6ab9a8334663ceca4483c7e4d73d0edffbcd2b99e74505472a10ff03a86"} 08:04:00 INFO - Running post-run listener: copy_logs_to_upload_dir 08:04:00 INFO - Copying logs to upload dir... 08:04:00 INFO - mkdir: /builds/slave/test/build/upload/logs 08:04:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.074914 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-17 08:04:00.580207) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 08:04:00.583659) ========= 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=eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003 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/34aa5f3d520ff6daea8cdce450484226701a3b5f32d1c4de153ff21fc07feba59156ccbcc548c06f92f477fc5c470660354fcd9e636653864dfa1345f0baa723", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/07f3931d8fe1546967f7e794cb3c7105fed3e4c4735cc75cb39bfb58d605b7e234594aa1fdd6d905c7ee623440992cdcb8a9b547327b8e637ad8ecec6e8c4f57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2126264a48a0aae1f13290caae168c62873c5fff811e9ac072117fbef8e412ef69f908cb112c239cb6e544f39a770ee11085c04f9524cfc6a25857757750327a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9a28fb0a5d69c2d1cb64cd7034b1461aba45e835b7b87570cdcb337ac8d8a8824b73a6ab9a8334663ceca4483c7e4d73d0edffbcd2b99e74505472a10ff03a86"} build_url:https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011497 build_url: 'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34aa5f3d520ff6daea8cdce450484226701a3b5f32d1c4de153ff21fc07feba59156ccbcc548c06f92f477fc5c470660354fcd9e636653864dfa1345f0baa723", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/07f3931d8fe1546967f7e794cb3c7105fed3e4c4735cc75cb39bfb58d605b7e234594aa1fdd6d905c7ee623440992cdcb8a9b547327b8e637ad8ecec6e8c4f57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2126264a48a0aae1f13290caae168c62873c5fff811e9ac072117fbef8e412ef69f908cb112c239cb6e544f39a770ee11085c04f9524cfc6a25857757750327a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9a28fb0a5d69c2d1cb64cd7034b1461aba45e835b7b87570cdcb337ac8d8a8824b73a6ab9a8334663ceca4483c7e4d73d0edffbcd2b99e74505472a10ff03a86"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 08:04:00.610837) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:04:00.611322) ========= 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=eca2ab57546de7b41f256b19000001ef-1487346171.716803-459915003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004512 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:04:00.694370) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 08:04:00.694706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 08:04:00.695057) ========= ========= Total master_lag: 0.17 =========