builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-051 starttime: 1487346074.28 results: success (0) revision: df9a0acc26486790c8794b787c764654d32c3f9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:14.279060) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:14.279541) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:14.279827) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:14.294112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:14.294672) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 07:41:14-- 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% 223M=0s 2017-02-17 07:41:14 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101268 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:14.407631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:14.407950) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016406 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:14.458032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 07:41:14.458396) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 07:41:14,690 truncating revision to first 12 chars 2017-02-17 07:41:14,690 Setting DEBUG logging. 2017-02-17 07:41:14,690 attempt 1/10 2017-02-17 07:41:14,690 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:41:15,962 unpacking tar archive at: mozilla-central-df9a0acc2648/testing/mozharness/ program finished with exit code 0 elapsedTime=1.580626 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 07:41:16.051935) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:16.052276) ========= script_repo_revision: df9a0acc26486790c8794b787c764654d32c3f9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:16.052666) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:16.052935) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 07:41:16.063250) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-02-17 07:41:16.063541) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:41:16 INFO - MultiFileLogger online at 20170217 07:41:16 in /builds/slave/test 07:41:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:41:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:41:16 INFO - {'append_to_log': False, 07:41:16 INFO - 'base_work_dir': '/builds/slave/test', 07:41:16 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:41:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:41:16 INFO - 'branch': 'Firefox-Non-PGO', 07:41:16 INFO - 'buildbot_json_path': 'buildprops.json', 07:41:16 INFO - 'config_files': ('talos/linux_config.py',), 07:41:16 INFO - 'default_actions': ('clobber', 07:41:16 INFO - 'read-buildbot-config', 07:41:16 INFO - 'download-and-extract', 07:41:16 INFO - 'populate-webroot', 07:41:16 INFO - 'create-virtualenv', 07:41:16 INFO - 'install', 07:41:16 INFO - 'run-tests'), 07:41:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:41:16 INFO - 'download_minidump_stackwalk': True, 07:41:16 INFO - 'download_symbols': 'ondemand', 07:41:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:41:16 INFO - 'tooltool.py': '/tools/tooltool.py', 07:41:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:41:16 INFO - '/tools/misc-python/virtualenv.py')}, 07:41:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:41:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:41:16 INFO - 'installer_path': 'installer.exe', 07:41:16 INFO - 'log_level': 'info', 07:41:16 INFO - 'log_name': 'talos', 07:41:16 INFO - 'log_to_console': True, 07:41:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:41:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:41:16 INFO - 'opt_config_files': (), 07:41:16 INFO - 'pip_index': False, 07:41:16 INFO - 'sps_profile': False, 07:41:16 INFO - 'sps_profile_interval': 0, 07:41:16 INFO - 'suite': 'dromaeojs-e10s', 07:41:16 INFO - 'system_bits': '32', 07:41:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:41:16 INFO - 'title': 'talos-linux64-ix-051', 07:41:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:41:16 INFO - 'use_talos_json': True, 07:41:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:41:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:41:16 INFO - 'work_dir': 'build'} 07:41:16 INFO - [mozharness: 2017-02-17 15:41:16.387537Z] Running clobber step. 07:41:16 INFO - Running pre-action listener: _resource_record_pre_action 07:41:16 INFO - Running main action method: clobber 07:41:16 INFO - rmtree: /builds/slave/test/build 07:41:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:41:17 INFO - Running post-action listener: _resource_record_post_action 07:41:17 INFO - [mozharness: 2017-02-17 15:41:17.345103Z] Finished clobber step (success) 07:41:17 INFO - [mozharness: 2017-02-17 15:41:17.345204Z] Running read-buildbot-config step. 07:41:17 INFO - Running pre-action listener: _resource_record_pre_action 07:41:17 INFO - Running main action method: read_buildbot_config 07:41:17 INFO - Using buildbot properties: 07:41:17 INFO - { 07:41:17 INFO - "project": "", 07:41:17 INFO - "product": "firefox", 07:41:17 INFO - "who": "ffxbld@noreply.mozilla.org", 07:41:17 INFO - "installer_path": "public/build/target.tar.bz2", 07:41:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 07:41:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:41:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 07:41:17 INFO - "stage_platform": "linux64", 07:41:17 INFO - "basedir": "/builds/slave/test", 07:41:17 INFO - "buildnumber": 426, 07:41:17 INFO - "platform": "ubuntu64_hw", 07:41:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:41:17 INFO - "slavebuilddir": "test", 07:41:17 INFO - "taskId": "ahmb367ZShChXlsD9qCsBw", 07:41:17 INFO - "branch": "mozilla-central", 07:41:17 INFO - "script_repo_revision": "production", 07:41:17 INFO - "revision": "df9a0acc26486790c8794b787c764654d32c3f9c", 07:41:17 INFO - "slavename": "talos-linux64-ix-051", 07:41:17 INFO - "repo_path": "mozilla-central" 07:41:17 INFO - } 07:41:17 INFO - Finding installer, test and symbols from parent task. 07:41:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ahmb367ZShChXlsD9qCsBw'}, attempt #1 07:41:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ahmb367ZShChXlsD9qCsBw'}, attempt #1 07:41:18 INFO - Task dependencies: P482Qw7lQhC0z1zIaZppAg 07:41:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg'}, attempt #1 07:41:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 07:41:18 INFO - Set test_url: https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/test_packages.json 07:41:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.crashreporter-symbols.zip 07:41:18 INFO - Running post-action listener: _resource_record_post_action 07:41:18 INFO - [mozharness: 2017-02-17 15:41:18.836256Z] Finished read-buildbot-config step (success) 07:41:18 INFO - [mozharness: 2017-02-17 15:41:18.836474Z] Running download-and-extract step. 07:41:18 INFO - Running pre-action listener: _resource_record_pre_action 07:41:18 INFO - Running main action method: download_and_extract 07:41:18 INFO - mkdir: /builds/slave/test/build/tests 07:41:18 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:41:18 INFO - trying https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.test_packages.json 07:41:18 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:41:18 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:41:19 INFO - Downloaded 896 bytes. 07:41:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:41:19 INFO - Using the following test package requirements: 07:41:19 INFO - {u'common': [u'target.common.tests.zip'], 07:41:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:41:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:41:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:41:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:41:19 INFO - u'mozbase': [u'target.common.tests.zip'], 07:41:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:41:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:41:19 INFO - u'web-platform': [u'target.common.tests.zip', 07:41:19 INFO - u'target.web-platform.tests.zip'], 07:41:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:41:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:41:19 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:41:19 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:41:19 INFO - Fetch https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.common.tests.zip into memory 07:41:21 INFO - Expected file size: 57027601 07:41:21 INFO - Obtained file size: 57027601 07:41:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:41:30 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:41:30 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:41:30 INFO - Fetch https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.common.tests.zip into memory 07:41:32 INFO - Expected file size: 57027601 07:41:32 INFO - Obtained file size: 57027601 07:41:42 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:41:42 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:41:42 INFO - Fetch https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.talos.tests.zip into memory 07:41:43 INFO - Expected file size: 14403647 07:41:43 INFO - Obtained file size: 14403647 07:41:43 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:41:43 INFO - trying https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 07:41:43 INFO - Downloading https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:41:43 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:41:45 INFO - Downloaded 61398070 bytes. 07:41:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 07:41:45 INFO - mkdir: /builds/slave/test/properties 07:41:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:41:45 INFO - Writing to file /builds/slave/test/properties/build_url 07:41:45 INFO - Contents: 07:41:45 INFO - build_url:https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 07:41:45 INFO - Running post-action listener: _resource_record_post_action 07:41:45 INFO - Running post-action listener: set_extra_try_arguments 07:41:45 INFO - [mozharness: 2017-02-17 15:41:45.547857Z] Finished download-and-extract step (success) 07:41:45 INFO - [mozharness: 2017-02-17 15:41:45.547954Z] Running populate-webroot step. 07:41:45 INFO - Running pre-action listener: _resource_record_pre_action 07:41:45 INFO - Running main action method: populate_webroot 07:41:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:41:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:41:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:41:45 INFO - '--tppagecycles', 07:41:45 INFO - '7'], 07:41:45 INFO - 'tests': ['tsvgm']}}, 07:41:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:41:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:41:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:41:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:41:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:41:45 INFO - 'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:41:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:41:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:41:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:41:45 INFO - 'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['damp', 'tps']}, 07:41:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:41:45 INFO - 'tests': ['damp', 'tps']}, 07:41:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['dromaeo_dom']}, 07:41:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:41:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['basic_compositor_video']}, 07:41:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:41:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['a11yr', 07:41:45 INFO - 'ts_paint', 07:41:45 INFO - 'tpaint', 07:41:45 INFO - 'sessionrestore', 07:41:45 INFO - 'sessionrestore_no_auto_restore', 07:41:45 INFO - 'tabpaint']}, 07:41:45 INFO - 'other-e10s': {'tests': ['a11yr', 07:41:45 INFO - 'ts_paint', 07:41:45 INFO - 'tpaint', 07:41:45 INFO - 'sessionrestore', 07:41:45 INFO - 'sessionrestore_no_auto_restore', 07:41:45 INFO - 'tabpaint']}, 07:41:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:41:45 INFO - 'ts_paint', 07:41:45 INFO - 'tpaint', 07:41:45 INFO - 'sessionrestore', 07:41:45 INFO - 'sessionrestore_no_auto_restore', 07:41:45 INFO - 'tabpaint']}, 07:41:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:41:45 INFO - 'ts_paint', 07:41:45 INFO - 'tpaint', 07:41:45 INFO - 'sessionrestore', 07:41:45 INFO - 'sessionrestore_no_auto_restore', 07:41:45 INFO - 'tabpaint']}, 07:41:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['a11yr', 07:41:45 INFO - 'ts_paint', 07:41:45 INFO - 'tpaint', 07:41:45 INFO - 'sessionrestore', 07:41:45 INFO - 'sessionrestore_no_auto_restore', 07:41:45 INFO - 'tabpaint']}, 07:41:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['a11yr', 07:41:45 INFO - 'ts_paint', 07:41:45 INFO - 'tpaint', 07:41:45 INFO - 'sessionrestore', 07:41:45 INFO - 'sessionrestore_no_auto_restore', 07:41:45 INFO - 'tabpaint']}, 07:41:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['tsvgx', 07:41:45 INFO - 'tsvgr_opacity', 07:41:45 INFO - 'tart', 07:41:45 INFO - 'tscrollx', 07:41:45 INFO - 'cart', 07:41:45 INFO - 'tsvg_static']}, 07:41:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:41:45 INFO - 'tsvgr_opacity', 07:41:45 INFO - 'tart', 07:41:45 INFO - 'tscrollx', 07:41:45 INFO - 'cart', 07:41:45 INFO - 'tsvg_static']}, 07:41:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:41:45 INFO - 'talos_options': ['--disable-e10s'], 07:41:45 INFO - 'tests': ['tp5o']}, 07:41:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:41:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:41:45 INFO - 'talos_options': ['--disable-e10s', 07:41:45 INFO - '--xperf_path', 07:41:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:41:45 INFO - 'tests': ['tp5n']}, 07:41:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:41:45 INFO - 'talos_options': ['--xperf_path', 07:41:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:41:45 INFO - 'tests': ['tp5n']}}, 07:41:45 INFO - 'talos.zip': {'path': '', 07:41:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:41:45 INFO - Running post-action listener: _resource_record_post_action 07:41:45 INFO - [mozharness: 2017-02-17 15:41:45.561075Z] Finished populate-webroot step (success) 07:41:45 INFO - [mozharness: 2017-02-17 15:41:45.561166Z] Running create-virtualenv step. 07:41:45 INFO - Running pre-action listener: _resource_record_pre_action 07:41:45 INFO - Running main action method: create_virtualenv 07:41:45 INFO - Creating virtualenv /builds/slave/test/build/venv 07:41:45 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:41:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:41:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:41:45 INFO - Using real prefix '/usr' 07:41:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:41:46 INFO - Installing distribute.............................................................................................................................................................................................done. 07:41:48 INFO - Installing pip.................done. 07:41:48 INFO - Return code: 0 07:41:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:41:48 INFO - Reading from file tmpfile_stdout 07:41:48 INFO - Output received: 07:41:48 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:41:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:41:48 INFO - Using env: {'DISPLAY': ':0', 07:41:48 INFO - 'HOME': '/home/cltbld', 07:41:48 INFO - 'LANG': 'en_US.UTF-8', 07:41:48 INFO - 'LANGUAGE': 'en_US:en', 07:41:48 INFO - 'LOGNAME': 'cltbld', 07:41:48 INFO - 'MAIL': '/var/mail/cltbld', 07:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:48 INFO - 'MOZ_NO_REMOTE': '1', 07:41:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:48 INFO - 'NO_EM_RESTART': '1', 07:41:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:48 INFO - 'PWD': '/builds/slave/test', 07:41:48 INFO - 'SHELL': '/bin/bash', 07:41:48 INFO - 'SHLVL': '1', 07:41:48 INFO - 'TERM': 'linux', 07:41:48 INFO - 'TMOUT': '86400', 07:41:48 INFO - 'USER': 'cltbld', 07:41:48 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:48 INFO - '_': '/tools/buildbot/bin/python'} 07:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:48 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:41:48 INFO - Cleaning up... 07:41:48 INFO - Return code: 0 07:41:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:41:48 INFO - Using env: {'DISPLAY': ':0', 07:41:48 INFO - 'HOME': '/home/cltbld', 07:41:48 INFO - 'LANG': 'en_US.UTF-8', 07:41:48 INFO - 'LANGUAGE': 'en_US:en', 07:41:48 INFO - 'LOGNAME': 'cltbld', 07:41:48 INFO - 'MAIL': '/var/mail/cltbld', 07:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:48 INFO - 'MOZ_NO_REMOTE': '1', 07:41:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:48 INFO - 'NO_EM_RESTART': '1', 07:41:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:48 INFO - 'PWD': '/builds/slave/test', 07:41:48 INFO - 'SHELL': '/bin/bash', 07:41:48 INFO - 'SHLVL': '1', 07:41:48 INFO - 'TERM': 'linux', 07:41:48 INFO - 'TMOUT': '86400', 07:41:48 INFO - 'USER': 'cltbld', 07:41:48 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:48 INFO - '_': '/tools/buildbot/bin/python'} 07:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:48 INFO - Downloading/unpacking psutil>=3.1.1 07:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:41:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:41:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:41:50 INFO - Installing collected packages: psutil 07:41:50 INFO - Running setup.py install for psutil 07:41:50 INFO - building 'psutil._psutil_linux' extension 07:41:50 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:41:51 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:41:51 INFO - building 'psutil._psutil_posix' extension 07:41:51 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:41:51 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:41:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:41:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:41:51 INFO - Successfully installed psutil 07:41:51 INFO - Cleaning up... 07:41:51 INFO - Return code: 0 07:41:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:41:51 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:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:51 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:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:51 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 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:41:51 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:41:51 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:41:51 INFO - Using env: {'DISPLAY': ':0', 07:41:51 INFO - 'HOME': '/home/cltbld', 07:41:51 INFO - 'LANG': 'en_US.UTF-8', 07:41:51 INFO - 'LANGUAGE': 'en_US:en', 07:41:51 INFO - 'LOGNAME': 'cltbld', 07:41:51 INFO - 'MAIL': '/var/mail/cltbld', 07:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:51 INFO - 'MOZ_NO_REMOTE': '1', 07:41:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:51 INFO - 'NO_EM_RESTART': '1', 07:41:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:51 INFO - 'PWD': '/builds/slave/test', 07:41:51 INFO - 'SHELL': '/bin/bash', 07:41:51 INFO - 'SHLVL': '1', 07:41:51 INFO - 'TERM': 'linux', 07:41:51 INFO - 'TMOUT': '86400', 07:41:51 INFO - 'USER': 'cltbld', 07:41:51 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:51 INFO - '_': '/tools/buildbot/bin/python'} 07:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:51 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:41:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:41:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:41:53 INFO - Installing collected packages: mozsystemmonitor 07:41:53 INFO - Running setup.py install for mozsystemmonitor 07:41:53 INFO - Successfully installed mozsystemmonitor 07:41:53 INFO - Cleaning up... 07:41:53 INFO - Return code: 0 07:41:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:41:53 INFO - Using env: {'DISPLAY': ':0', 07:41:53 INFO - 'HOME': '/home/cltbld', 07:41:53 INFO - 'LANG': 'en_US.UTF-8', 07:41:53 INFO - 'LANGUAGE': 'en_US:en', 07:41:53 INFO - 'LOGNAME': 'cltbld', 07:41:53 INFO - 'MAIL': '/var/mail/cltbld', 07:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:53 INFO - 'MOZ_NO_REMOTE': '1', 07:41:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:53 INFO - 'NO_EM_RESTART': '1', 07:41:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:53 INFO - 'PWD': '/builds/slave/test', 07:41:53 INFO - 'SHELL': '/bin/bash', 07:41:53 INFO - 'SHLVL': '1', 07:41:53 INFO - 'TERM': 'linux', 07:41:53 INFO - 'TMOUT': '86400', 07:41:53 INFO - 'USER': 'cltbld', 07:41:53 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:53 INFO - '_': '/tools/buildbot/bin/python'} 07:41:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:54 INFO - Downloading/unpacking jsonschema==2.5.1 07:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:41:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 INFO - Downloading functools32-3.2.3-2.tar.gz 07:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:41:56 INFO - warning: no files found matching '*.txt' 07:41:56 INFO - no previously-included directories found matching 'build' 07:41:56 INFO - no previously-included directories found matching 'dist' 07:41:56 INFO - no previously-included directories found matching '.git*' 07:41:56 INFO - Installing collected packages: jsonschema, functools32 07:41:56 INFO - Running setup.py install for functools32 07:41:56 INFO - warning: no files found matching '*.txt' 07:41:56 INFO - no previously-included directories found matching 'build' 07:41:56 INFO - no previously-included directories found matching 'dist' 07:41:56 INFO - no previously-included directories found matching '.git*' 07:41:56 INFO - Successfully installed jsonschema functools32 07:41:56 INFO - Cleaning up... 07:41:56 INFO - Return code: 0 07:41:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:56 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 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:41:56 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:41:56 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:41:56 INFO - Using env: {'DISPLAY': ':0', 07:41:56 INFO - 'HOME': '/home/cltbld', 07:41:56 INFO - 'LANG': 'en_US.UTF-8', 07:41:56 INFO - 'LANGUAGE': 'en_US:en', 07:41:56 INFO - 'LOGNAME': 'cltbld', 07:41:56 INFO - 'MAIL': '/var/mail/cltbld', 07:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:56 INFO - 'MOZ_NO_REMOTE': '1', 07:41:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:56 INFO - 'NO_EM_RESTART': '1', 07:41:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:56 INFO - 'PWD': '/builds/slave/test', 07:41:56 INFO - 'SHELL': '/bin/bash', 07:41:56 INFO - 'SHLVL': '1', 07:41:56 INFO - 'TERM': 'linux', 07:41:56 INFO - 'TMOUT': '86400', 07:41:56 INFO - 'USER': 'cltbld', 07:41:56 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:56 INFO - '_': '/tools/buildbot/bin/python'} 07:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:41:57 INFO - Cleaning up... 07:41:57 INFO - Return code: 0 07:41:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:41: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:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41: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:41:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:41:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:41:57 INFO - Using env: {'DISPLAY': ':0', 07:41:57 INFO - 'HOME': '/home/cltbld', 07:41:57 INFO - 'LANG': 'en_US.UTF-8', 07:41:57 INFO - 'LANGUAGE': 'en_US:en', 07:41:57 INFO - 'LOGNAME': 'cltbld', 07:41:57 INFO - 'MAIL': '/var/mail/cltbld', 07:41:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:57 INFO - 'MOZ_NO_REMOTE': '1', 07:41:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:57 INFO - 'NO_EM_RESTART': '1', 07:41:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:57 INFO - 'PWD': '/builds/slave/test', 07:41:57 INFO - 'SHELL': '/bin/bash', 07:41:57 INFO - 'SHLVL': '1', 07:41:57 INFO - 'TERM': 'linux', 07:41:57 INFO - 'TMOUT': '86400', 07:41:57 INFO - 'USER': 'cltbld', 07:41:57 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:41:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:57 INFO - '_': '/tools/buildbot/bin/python'} 07:41:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:57 INFO - Downloading/unpacking blobuploader==1.2.4 07:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:59 INFO - Downloading blobuploader-1.2.4.tar.gz 07:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:41:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:41:59 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:41:59 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:41:59 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:41:59 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:41:59 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:41:59 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:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:41:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:41:59 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:41:59 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:41:59 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:41:59 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:41:59 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:41:59 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:41:59 INFO - Downloading docopt-0.6.1.tar.gz 07:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:42:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:42:00 INFO - Running setup.py install for blobuploader 07:42:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:42:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:42:00 INFO - Running setup.py install for requests 07:42:00 INFO - Running setup.py install for docopt 07:42:00 INFO - Successfully installed blobuploader requests docopt 07:42:00 INFO - Cleaning up... 07:42:00 INFO - Return code: 0 07:42:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42: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:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42: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:42:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:00 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 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:42:00 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:42:00 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:42:00 INFO - Using env: {'DISPLAY': ':0', 07:42:00 INFO - 'HOME': '/home/cltbld', 07:42:00 INFO - 'LANG': 'en_US.UTF-8', 07:42:00 INFO - 'LANGUAGE': 'en_US:en', 07:42:00 INFO - 'LOGNAME': 'cltbld', 07:42:00 INFO - 'MAIL': '/var/mail/cltbld', 07:42:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:00 INFO - 'MOZ_NO_REMOTE': '1', 07:42:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:42:00 INFO - 'NO_EM_RESTART': '1', 07:42:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:42:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:00 INFO - 'PWD': '/builds/slave/test', 07:42:00 INFO - 'SHELL': '/bin/bash', 07:42:00 INFO - 'SHLVL': '1', 07:42:00 INFO - 'TERM': 'linux', 07:42:00 INFO - 'TMOUT': '86400', 07:42:00 INFO - 'USER': 'cltbld', 07:42:00 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:42:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:00 INFO - '_': '/tools/buildbot/bin/python'} 07:42:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:00 INFO - Running setup.py (path:/tmp/pip-0H8281-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:00 INFO - Running setup.py (path:/tmp/pip-mP8bnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:01 INFO - Running setup.py (path:/tmp/pip-lfg7sC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:01 INFO - Running setup.py (path:/tmp/pip-Hu9RIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:01 INFO - Running setup.py (path:/tmp/pip-oUuPr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:01 INFO - Running setup.py (path:/tmp/pip-UnDoGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:01 INFO - Running setup.py (path:/tmp/pip-MLzswZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:01 INFO - Running setup.py (path:/tmp/pip-kmy8oK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:01 INFO - Running setup.py (path:/tmp/pip-19pKTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:01 INFO - Running setup.py (path:/tmp/pip-0HxN86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:01 INFO - Running setup.py (path:/tmp/pip-7S6_Up-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:01 INFO - Running setup.py (path:/tmp/pip-3cxeqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:01 INFO - Running setup.py (path:/tmp/pip-FJMgHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:01 INFO - Running setup.py (path:/tmp/pip-BSZ0Cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:01 INFO - Running setup.py (path:/tmp/pip-2wQqVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:01 INFO - Running setup.py (path:/tmp/pip-pDCfKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:02 INFO - Running setup.py (path:/tmp/pip-JWGFAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:42:02 INFO - Running setup.py install for manifestparser 07:42:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Running setup.py install for mozcrash 07:42:02 INFO - Running setup.py install for mozdebug 07:42:02 INFO - Running setup.py install for mozdevice 07:42:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Running setup.py install for mozfile 07:42:02 INFO - Running setup.py install for mozhttpd 07:42:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Running setup.py install for mozinfo 07:42:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Running setup.py install for mozInstall 07:42:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:42:02 INFO - Running setup.py install for mozleak 07:42:03 INFO - Running setup.py install for mozlog 07:42:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:42:03 INFO - Running setup.py install for moznetwork 07:42:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:42:03 INFO - Running setup.py install for mozprocess 07:42:03 INFO - Running setup.py install for mozprofile 07:42:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:42:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:42:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:42:03 INFO - Running setup.py install for mozrunner 07:42:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:42:03 INFO - Running setup.py install for mozscreenshot 07:42:03 INFO - Running setup.py install for moztest 07:42:03 INFO - Running setup.py install for mozversion 07:42:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:42:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:42:03 INFO - Cleaning up... 07:42:03 INFO - Return code: 0 07:42:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:03 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 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:42:03 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:42:03 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:42:03 INFO - Using env: {'DISPLAY': ':0', 07:42:03 INFO - 'HOME': '/home/cltbld', 07:42:03 INFO - 'LANG': 'en_US.UTF-8', 07:42:03 INFO - 'LANGUAGE': 'en_US:en', 07:42:03 INFO - 'LOGNAME': 'cltbld', 07:42:03 INFO - 'MAIL': '/var/mail/cltbld', 07:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:03 INFO - 'MOZ_NO_REMOTE': '1', 07:42:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:42:03 INFO - 'NO_EM_RESTART': '1', 07:42:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:03 INFO - 'PWD': '/builds/slave/test', 07:42:03 INFO - 'SHELL': '/bin/bash', 07:42:03 INFO - 'SHLVL': '1', 07:42:03 INFO - 'TERM': 'linux', 07:42:03 INFO - 'TMOUT': '86400', 07:42:03 INFO - 'USER': 'cltbld', 07:42:03 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:03 INFO - '_': '/tools/buildbot/bin/python'} 07:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:42:04 INFO - Running setup.py (path:/tmp/pip-v6ZxWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:42:04 INFO - Running setup.py (path:/tmp/pip-vhiAVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:42:04 INFO - Running setup.py (path:/tmp/pip-A9u6xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:42:04 INFO - Running setup.py (path:/tmp/pip-iBoScV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:42:04 INFO - Running setup.py (path:/tmp/pip-pxyAUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:42:04 INFO - Running setup.py (path:/tmp/pip-QtYuxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:42:04 INFO - Running setup.py (path:/tmp/pip-sdAq0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:42:04 INFO - Running setup.py (path:/tmp/pip-8Y3hPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:42:04 INFO - Running setup.py (path:/tmp/pip-45oW0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:42:04 INFO - Running setup.py (path:/tmp/pip-rmtciv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:42:04 INFO - Running setup.py (path:/tmp/pip-hOwitv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:42:04 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:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:42:04 INFO - Running setup.py (path:/tmp/pip-Csu8tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:42:05 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:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:42:05 INFO - Running setup.py (path:/tmp/pip-CyuAWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:42:05 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:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:42:05 INFO - Running setup.py (path:/tmp/pip-MVOgSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:42:05 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:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:05 INFO - Running setup.py (path:/tmp/pip-YxoL5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:42:05 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:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:42:05 INFO - Running setup.py (path:/tmp/pip-1W5yZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:42:05 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:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:42:05 INFO - Running setup.py (path:/tmp/pip-7bdJkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:42:05 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:42:05 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:42:05 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:42:05 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:42:05 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:42:05 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:42:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:42:07 INFO - Downloading blessings-1.6.tar.gz 07:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:42:07 INFO - Installing collected packages: blessings 07:42:07 INFO - Running setup.py install for blessings 07:42:07 INFO - Successfully installed blessings 07:42:07 INFO - Cleaning up... 07:42:07 INFO - Return code: 0 07:42:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:42:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:07 INFO - Reading from file tmpfile_stdout 07:42:07 INFO - Current package versions: 07:42:07 INFO - argparse == 1.2.1 07:42:07 INFO - blessings == 1.6 07:42:07 INFO - blobuploader == 1.2.4 07:42:07 INFO - docopt == 0.6.1 07:42:07 INFO - functools32 == 3.2.3-2 07:42:07 INFO - jsonschema == 2.5.1 07:42:07 INFO - manifestparser == 1.1 07:42:07 INFO - mozInstall == 1.12 07:42:07 INFO - mozcrash == 1.0 07:42:07 INFO - mozdebug == 0.1 07:42:07 INFO - mozdevice == 0.49 07:42:07 INFO - mozfile == 1.2 07:42:07 INFO - mozhttpd == 0.7 07:42:07 INFO - mozinfo == 0.9 07:42:07 INFO - mozleak == 0.1 07:42:07 INFO - mozlog == 3.4 07:42:07 INFO - moznetwork == 0.27 07:42:07 INFO - mozprocess == 0.24 07:42:07 INFO - mozprofile == 0.28 07:42:07 INFO - mozrunner == 6.13 07:42:07 INFO - mozscreenshot == 0.1 07:42:07 INFO - mozsystemmonitor == 0.3 07:42:07 INFO - moztest == 0.8 07:42:07 INFO - mozversion == 1.4 07:42:07 INFO - psutil == 3.1.1 07:42:07 INFO - requests == 1.2.3 07:42:07 INFO - wsgiref == 0.1.2 07:42:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:07 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 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:42:07 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:42:07 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:42:07 INFO - Using env: {'DISPLAY': ':0', 07:42:07 INFO - 'HOME': '/home/cltbld', 07:42:07 INFO - 'LANG': 'en_US.UTF-8', 07:42:07 INFO - 'LANGUAGE': 'en_US:en', 07:42:07 INFO - 'LOGNAME': 'cltbld', 07:42:07 INFO - 'MAIL': '/var/mail/cltbld', 07:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:07 INFO - 'MOZ_NO_REMOTE': '1', 07:42:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:42:07 INFO - 'NO_EM_RESTART': '1', 07:42:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:07 INFO - 'PWD': '/builds/slave/test', 07:42:07 INFO - 'SHELL': '/bin/bash', 07:42:07 INFO - 'SHLVL': '1', 07:42:07 INFO - 'TERM': 'linux', 07:42:07 INFO - 'TMOUT': '86400', 07:42:07 INFO - 'USER': 'cltbld', 07:42:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:07 INFO - '_': '/tools/buildbot/bin/python'} 07:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:08 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:42:08 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:42:08 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:42:08 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:42:08 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:42:08 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:42:08 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:42:08 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:42:08 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:42:08 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:42:08 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:42:08 INFO - Cleaning up... 07:42:08 INFO - Return code: 0 07:42:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:42:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x146f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1567a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x170cb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', '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:42:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:42:08 INFO - Using env: {'DISPLAY': ':0', 07:42:08 INFO - 'HOME': '/home/cltbld', 07:42:08 INFO - 'LANG': 'en_US.UTF-8', 07:42:08 INFO - 'LANGUAGE': 'en_US:en', 07:42:08 INFO - 'LOGNAME': 'cltbld', 07:42:08 INFO - 'MAIL': '/var/mail/cltbld', 07:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:08 INFO - 'MOZ_NO_REMOTE': '1', 07:42:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:42:08 INFO - 'NO_EM_RESTART': '1', 07:42:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:08 INFO - 'PWD': '/builds/slave/test', 07:42:08 INFO - 'SHELL': '/bin/bash', 07:42:08 INFO - 'SHLVL': '1', 07:42:08 INFO - 'TERM': 'linux', 07:42:08 INFO - 'TMOUT': '86400', 07:42:08 INFO - 'USER': 'cltbld', 07:42:08 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:08 INFO - '_': '/tools/buildbot/bin/python'} 07:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:42:08 INFO - Cleaning up... 07:42:08 INFO - Return code: 0 07:42:08 INFO - Running post-action listener: _resource_record_post_action 07:42:08 INFO - Running post-action listener: _start_resource_monitoring 07:42:08 INFO - Starting resource monitoring. 07:42:08 INFO - [mozharness: 2017-02-17 15:42:08.407426Z] Finished create-virtualenv step (success) 07:42:08 INFO - [mozharness: 2017-02-17 15:42:08.407944Z] Running install step. 07:42:08 INFO - Running pre-action listener: _resource_record_pre_action 07:42:08 INFO - Running main action method: install 07:42:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:42:08 INFO - Reading from file tmpfile_stdout 07:42:08 INFO - Detecting whether we're running mozinstall >=1.0... 07:42:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:42:08 INFO - Reading from file tmpfile_stdout 07:42:08 INFO - Output received: 07:42:08 INFO - Usage: mozinstall [options] installer 07:42:08 INFO - Options: 07:42:08 INFO - -h, --help show this help message and exit 07:42:08 INFO - -d DEST, --destination=DEST 07:42:08 INFO - Directory to install application into. [default: 07:42:08 INFO - "/builds/slave/test"] 07:42:08 INFO - --app=APP Application being installed. [default: firefox] 07:42:08 INFO - mkdir: /builds/slave/test/build/application 07:42:08 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:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:42:24 INFO - Reading from file tmpfile_stdout 07:42:24 INFO - Output received: 07:42:24 INFO - /builds/slave/test/build/application/firefox/firefox 07:42:24 INFO - Running post-action listener: _resource_record_post_action 07:42:24 INFO - [mozharness: 2017-02-17 15:42:24.086573Z] Finished install step (success) 07:42:24 INFO - [mozharness: 2017-02-17 15:42:24.086910Z] Running run-tests step. 07:42:24 INFO - Running pre-action listener: _resource_record_pre_action 07:42:24 INFO - Running main action method: run_tests 07:42:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:42:24 INFO - Python 2.7.3 07:42:24 INFO - Return code: 0 07:42:24 INFO - grabbing minidump binary from tooltool 07:42:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:42:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x170a290>, '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 0x170ab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x170b040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:42:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:42:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 07:42:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:42:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:42:24 INFO - Return code: 0 07:42:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:42:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:42:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:42:24 INFO - ENV: RUST_BACKTRACE is now 1 07:42:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:42:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:42:24 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-e10s', '--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-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:42:24 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-e10s --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-051 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:42:24 INFO - Using env: {'DISPLAY': ':0', 07:42:24 INFO - 'HOME': '/home/cltbld', 07:42:24 INFO - 'LANG': 'en_US.UTF-8', 07:42:24 INFO - 'LANGUAGE': 'en_US:en', 07:42:24 INFO - 'LOGNAME': 'cltbld', 07:42:24 INFO - 'MAIL': '/var/mail/cltbld', 07:42:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:42:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:24 INFO - 'MOZ_NO_REMOTE': '1', 07:42:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:42:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:42:24 INFO - 'NO_EM_RESTART': '1', 07:42:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:24 INFO - 'PWD': '/builds/slave/test', 07:42:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:42:24 INFO - 'RUST_BACKTRACE': '1', 07:42:24 INFO - 'SHELL': '/bin/bash', 07:42:24 INFO - 'SHLVL': '1', 07:42:24 INFO - 'TERM': 'linux', 07:42:24 INFO - 'TMOUT': '86400', 07:42:24 INFO - 'USER': 'cltbld', 07:42:24 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819', 07:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:24 INFO - '_': '/tools/buildbot/bin/python'} 07:42:24 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-e10s', '--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-051', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:42:24 INFO - mozversion application_buildid: 20170217150643 07:42:24 INFO - mozversion application_changeset: df9a0acc26486790c8794b787c764654d32c3f9c 07:42:24 INFO - mozversion application_display_name: Nightly 07:42:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:42:24 INFO - mozversion application_name: Firefox 07:42:24 INFO - mozversion application_remotingname: firefox 07:42:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:42:24 INFO - mozversion application_vendor: Mozilla 07:42:24 INFO - mozversion application_version: 54.0a1 07:42:24 INFO - mozversion platform_buildid: 20170217150643 07:42:24 INFO - mozversion platform_changeset: df9a0acc26486790c8794b787c764654d32c3f9c 07:42:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:42:24 INFO - mozversion platform_version: 54.0a1 07:42:24 INFO - using testdate: 1487346144 07:42:24 INFO - actual date: 1487346144 07:42:24 INFO - starting webserver on 'localhost:56803' 07:42:24 INFO - SUITE-START | Running 2 tests 07:42:24 INFO - TEST-START | dromaeo_css 07:42:24 INFO - operating with platform_type : linux_ 07:42:24 INFO - Initialising browser for dromaeo_css test... 07:42:24 INFO - TEST-INFO | started process 32100 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG3K711/profile http://localhost:56803/getInfo.html) 07:42:27 INFO - PROCESS | 32100 | Iterating over profile /tmp/tmpG3K711/profile 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/addons.json = Fri Feb 17 2017 07:42:27 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/crashes = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/extensions.ini = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/cache2 = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/sessionCheckpoints.json = Fri Feb 17 2017 07:42:28 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/minidumps = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/permissions.sqlite = Fri Feb 17 2017 07:42:25 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/key3.db = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/prefs.js = Fri Feb 17 2017 07:41:43 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | Stat failure 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/thumbnails = Fri Feb 17 2017 07:42:26 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/cert8.db = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/pluginreg.dat = Fri Feb 17 2017 07:42:26 GMT-0800 (PST) 07:42:28 INFO - PROCESS | 32100 | __metrics Screen width/height:1600/1200 07:42:28 INFO - PROCESS | 32100 | colorDepth:24 07:42:28 INFO - PROCESS | 32100 | Browser inner width/height: 1024/697 07:42:28 INFO - PROCESS | 32100 | __metrics 07:42:28 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:28 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/blocklist.xml = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/.parentlock = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/gmp = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/compatibility.ini = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/places.sqlite = Fri Feb 17 2017 07:42:28 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/content-prefs.sqlite = Fri Feb 17 2017 07:42:26 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/frequencyCap.json = Fri Feb 17 2017 07:42:27 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/secmod.db = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/bookmarkbackups = Fri Feb 17 2017 07:42:26 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/user.js = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/places.sqlite-wal = Fri Feb 17 2017 07:42:28 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/directoryLinks.json = Fri Feb 17 2017 07:42:27 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/safebrowsing = Fri Feb 17 2017 07:42:28 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/startupCache = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/extensions.json = Fri Feb 17 2017 07:42:27 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/localstore.rdf = Fri Feb 17 2017 07:41:43 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/places.sqlite-shm = Fri Feb 17 2017 07:42:25 GMT-0800 (PST) 07:42:29 INFO - PROCESS | 32100 | No birth date. Using mtime. 07:42:29 INFO - PROCESS | 32100 | Using date: /tmp/tmpG3K711/profile/extensions = Fri Feb 17 2017 07:42:24 GMT-0800 (PST) 07:42:29 ERROR - PROCESS | 32100 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 07:42:30 INFO - TEST-INFO | 32100: exit 0 07:42:30 INFO - Browser initialized. 07:42:30 INFO - Running cycle 1/1 for dromaeo_css test... 07:42:30 INFO - TEST-INFO | started process 32230 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpG3K711/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:42:42 INFO - PROCESS | 32230 | RSS: Main: 153452544 07:42:42 INFO - PROCESS | 32230 | 07:45:47 INFO - PROCESS | 32230 | Cycle 1(1): loaded http://localhost:56803/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56803/tests/dromaeo/cssquery-ext.html) 07:45:49 INFO - PROCESS | 32230 | RSS: Main: 163221504 07:45:49 INFO - PROCESS | 32230 | 07:47:31 INFO - PROCESS | 32230 | ************************* 07:47:31 INFO - PROCESS | 32230 | A coding exception was thrown and uncaught in a Task. 07:47:31 INFO - PROCESS | 32230 | 07:47:31 ERROR - PROCESS | 32230 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:47:31 INFO - PROCESS | 32230 | Full stack: 07:47:31 INFO - PROCESS | 32230 | ************************* 07:48:51 INFO - PROCESS | 32230 | Cycle 1(1): loaded http://localhost:56803/tests/dromaeo/cssquery-ext.html (next: http://localhost:56803/tests/dromaeo/cssquery-jquery.html) 07:48:52 INFO - PROCESS | 32230 | RSS: Main: 159215616 07:48:52 INFO - PROCESS | 32230 | 07:51:54 INFO - PROCESS | 32230 | Cycle 1(1): loaded http://localhost:56803/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56803/tests/dromaeo/cssquery-mootools.html) 07:51:55 INFO - PROCESS | 32230 | RSS: Main: 167890944 07:51:55 INFO - PROCESS | 32230 | 07:54:57 INFO - PROCESS | 32230 | Cycle 1(1): loaded http://localhost:56803/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56803/tests/dromaeo/cssquery-prototype.html) 07:55:01 INFO - PROCESS | 32230 | RSS: Main: 158650368 07:55:01 INFO - PROCESS | 32230 | 07:58:04 INFO - PROCESS | 32230 | Cycle 1(1): loaded http://localhost:56803/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56803/tests/dromaeo/cssquery-yui.html) 07:58:05 INFO - PROCESS | 32230 | RSS: Main: 162729984 07:58:05 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | Cycle 1(1): loaded http://localhost:56803/tests/dromaeo/cssquery-yui.html (next: http://localhost:56803/tests/dromaeo/cssquery-dojo.html) 08:01:15 INFO - PROCESS | 32230 | RSS: Main: 163733504 08:01:15 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | __start_tp_report 08:01:15 INFO - PROCESS | 32230 | _x_x_mozilla_page_load 08:01:15 INFO - PROCESS | 32230 | _x_x_mozilla_page_load_details 08:01:15 INFO - PROCESS | 32230 | |i|pagename|runs| 08:01:15 INFO - PROCESS | 32230 | |0;dojo.html;2594.623779551965;2621.4494956059225;2626.1858823764624;2641.0624228398906;2647.047063057299;4696.342512048307;4748.192807222772;4774.594159496442;4778.71327720337;4814.1575224335775;4723.055388922212;4745.762711864401;4746.406699162605;4750.501197374278;4790.904181916359;4114.362273847543;4298.204832106055;4368.235558777217;4419.160359531695;4542.386777785006;4419.801108950105;4435.822567097296;4444.400005999198;4486.371907932892;4522.299043648223;4304.7632380219075;4370.8907277318;4371.540988196257;4387.210302145612;4424.646028317727;4343.153085148398;4361.760103194323;4377.080813029268;4382.249494873912;4440.666949978732;4305.827766889321;4354.542773008852;4376.5623437656295;4384.298512238027;4417.712848664826;4531.410916580857;4558.749268790215;4659.673822832403;4670.766461676936;4672.462666793294;5037.949620503785;5062.594992400599;5063.569596584295;5064.468230835783;5089.898202035975;5046.84859454217;5061.240813877911;5084;5087.516685914869;5091.847244582631;4589.655775816827;4631.606313463326;4691.554302341272;4710.340552322609;4745.762711864393;4955.925661115086;5169.689818610896;5195.246689230041;5226.686398816083;5257;4773.570378665937;4930.975345123252;4955.776990035457;5012.1980483122525;5086;3886.7862267574988;4513.345564893072;4535.251683472256;4573.176828170961;5096.108181068298;4379.4525684289465;4502.3246513023305;4556.066006468666;4867.659263851497;4874.951250487521;4916.410030796327;5008.84973450797;5023.648344615842;5076.796928122908;5081.983603279359;4336.4145840311785;4339.240632889232;4342.109867477159;4370.606645401929;4370.672199585044;3744.9063773405883;3756.0609847538117;3762.793046382475;3794.317022935898;3796.6393192646656;4314.913701725948;4348.717333373384;4350.347447882843;4383.846565370197;4478.932816007762;5011.498850114959;5013.248012798036;5015.473375295542;5048.7475626219275;5061.949380506221;4379.343098535245;4384.254676704968;4394.2749446340995;4415.955840441555;4428.756418396954;4451.554844515587;4472.619827314649;4520;4523.88690282739;4532.456105267389;4222.683298752544;4227.365895115634;4254.553271906529;4271.893202669834;4690.671652984258;4475.172093162775;4477.462704475483;4518.841840535697;4924.556789888928;5144.074066668036;4693.084848454519;4719.4100737407825;4790.760461976817;4802.759862006956;4810.230363141881;4899.265110233351;4936.901261974812;4945.777440015136;4958.677685950402;4959.60323174138;5001.124803159505;5017.548420642013;5052.393712754493;5056.039352523009;5119.692818430757;4831.661783675249;4863.927041094457;4879.926801098058;4896.485868983706;4908;4868.075065737498;4875.317455556154;4875.902481950412;4918.0901533216465;4924.113659541317;4646.3727396801;4769.165396055746;4782.426108866958;4794.184988552024;4816.229403295456;4748.833790817304;4994.675346102637;5005.224190250517;5065.468125846733;5123.38519377677;4916.164252077227;4949.133901567139;4957.900841983213;4964.453910069961;5000.67495612784 08:01:15 INFO - PROCESS | 32230 | |1;ext.html;529.1480716047164;539.2719828231885;547.879466517366;550.1857251268123;561.0798290803084;32659.510107348407;34379.48430773537;34422.65577344226;34450.483242751376;34470.8276458618;33026.83486582564;34084.48873266903;36921;38440.80779596105;38675.806620966854;7366.226546212651;7663.808404789897;7672.539647621133;7701.229877012282;9308.767280818;9296.023917488668;9487.952560237189;9546.570404331787;9560;9560.760980975461;9440.763980900498;9501.80996380072;9553.379030363005;9564.330496865223;9570.47362395068;8426.241638252555;8522.74431767048;8531.701390451335;8534.743957681218;8563.400561960665;8297.460665056751;8347.373946954003;9358.906410935871;9379.108984646447;9401.482918439484;32161.839190803898;32410.351792964244;32490.837545812356;32499;32562.511562326585;3409.4203985322556;3483.477478378238;3502.3345564342694;3532.293541291752;3543.3090547343277;24804;24806.5038699225;24861.378465538324;25038.749612504005;25359.619605705928;3273.3548911857497;3343.9665603343897;3350.279689866666;3370.5186406667585;3402.0984439123654;218767;219583;220020.69968950478;221786.5838720478;222509;38346.616533834866;39607.80196099001;46879;47014.76492617515;47441.76279118582;48712.51287487151;49077;49440;54790;55279.72360138174;11960.521579136737;12024.819627705592;12069.15515913895;12069.215500992408;12101.213421127599;8675.783105422288;8692.78268043304;8694;8703.6518539259;8720.651173953098;15716.371345146295;15748.68502629941;15788.131652759208;15793;15796.763048554281;6424.935750642528;6464.935350646528;6634.13756211699;6660.6336651484635;6818.590884546844;745.4409193105171;755.7279379423403;759.7227012140529;763.969441222356;773.6595897804948;26233.868830655723;27176.864115679295;27313.31716707098;27425.725742742718;27685.446291074066;890.3500444675424;899.0874262623443;900.8558630619201;908.5366463103733;928.1785619726455;14283.357248923823;14450.566483005527;14500.492482763053;14697.706045879022;14709.779353309708;341190;341583;343114;343348;343630;49244;49942.750286249066;50163;50231.74884125556;50246.748766256664;1109.1847492093304;1139.2537887683582;1143.7140714821294;1146.9483873225558;1167.4629670351733;24758.876205618857;25054.62418063767;26677;26682.733172668803;26749;40790;40809.79595102005;41033.384499233136;41129.38305925473;41268;262855;263299;263326;265296;265328;21837.672434913173;22981.77018229796;23042.308730738103;24025.879870600536;24070;35741.285174296885;35775.105622359646;36242.456363154044;36370.4544431839;36913.26173476569;30641;30787.692123079378;30839.845800770854;31307.07069706118;31485;30351.696483034888;31190;31474.685253147174;31850.522242165924;32252;29622.703772961995;30052.3989520204;30263.243418914702;30346.393072138875;30799.692003080578;25958.22125336243;26623.733762662127;27930;28004.159875203775;28218.435631286848;21436.678449823576;21527;21644.458888527282;21682.457938551663;21903.12387504482 08:01:15 INFO - PROCESS | 32230 | |2;jquery.html;389.8830350894731;397.7394806401808;399.19960479239137;402.6899287548588;421.2916623093357;17439.738403923937;17768.378106766264;18126.18432170552;18157.99999999998;18624.906875465636;17423.564410889736;17711.91144044278;17767.289308427647;17911;17920.910395448005;5178.223266510016;5195.532402083812;5221.765020574083;5385.623006389555;5401.027814993299;5262.184361423966;5264.92102618461;5282.788688452457;5290;5308.336457942757;5186.636935414523;5205.9219111713355;5246.37043554772;5261.473852614727;5353.732313384335;5486.725663716818;5621.353544342412;5641.802536911188;5658.207850900877;5663.886722265532;4299;4930.4330002049865;5036.974815125937;5546;5550.972245138749;11147.888521114848;11212.27120237182;11222;11247.718807029889;11272.210945233837;2081.5628717969244;2154.256781410411;2178.7167668203033;2185.967210491844;2202.812760915324;8848.911510884873;9091.818163636757;9523.761905952337;9551.952240238823;9640.662576809778;1655.114513735158;1661.9335226591043;1666.166916541729;1695.1693670101686;1720.3118752498976;71462.64268678623;74375.62812185958;75803;76334.61832690856;76401.61799191024;9985.004498650376;10018.699439016842;10027;10397.896021039844;10586.311889727298;14690.853091469162;14708.117512949044;14709;14723.705525889636;14803.777943330859;6444.6133232005295;6743.494237932175;6791.388775010174;6905.482088843357;6930.757423490154;6122.54080943931;6164.075388691643;6346.27017892939;6347.206599175103;6370.77702280418;4207.600277973587;4254.319308910622;4285.485741710952;4381.78091095444;4507.57178068083;1858.7676540432446;1919.7120431935043;1922.6731455652573;1929.4693959161343;1940.805919408076;792.9326007289329;827.9710210142737;831.3307787231216;832.0930186097183;834.7579202031343;16322.83677163237;16824.327026918785;17465.7380139298;17599.47201583929;18022.3692170776;800.371708209053;804.2480280937342;813.7762115418308;825.3727167352855;835.7242110103651;9180.21968132703;9384;9599.984003359234;9770.413775173512;9821.410715357102;422349;423903;424626;435550;442498;67320;69564.95652565216;82907;82955;88892.5555372219;923.2936803345447;953.4231789767091;970.4904924914476;972.7665360313474;977.6822532676908;16057.357705691642;16164.676706465569;16754.66490670204;17000;17077;36531.634683652825;38320.80839595784;38692.41961370526;39316.213675726896;39998.40002400024;46854.76572617252;49294.26058609052;50630;50883;51422.48577514177;6834.589924604539;6923.861522769617;7042.859142817218;7705.88441173394;7827.686892524235;7988.281054705104;10754.838677419688;10857.457127143769;11000.394978276272;12068.155229133876;10012.549435275285;10135.54390052464;10396.324238924446;10399.584016639552;10597.07078519917;10366.844497332697;10433.582656693647;10529.894701052892;10722.51748671296;10951.671449856518;5899.380565040609;8494.320454363513;10529.473526323807;10768.676939691823;11053.502592383202;7756.534607923542;7838.216178382117;7943.126256111707;8001.159878212704;8091.797705057185;8965.372423930263;9683.903160968583;9952.303338766507;9975.850362244428;11403.828942566033 08:01:15 INFO - PROCESS | 32230 | |3;mootools.html;1319.122783349073;1341.1684755451608;1344.1128854955732;1349.2511656030902;1354.1942544186209;7045.682944267507;7162.283771622835;7358.264173582639;7394.519356241847;7464.664090115951;7400.074990626172;7531.887021694679;7537.585432801194;7539.698412063524;7559.7354092606765;2058.0738667599567;2090.80137386948;2100.1284466946277;2102.1171108134545;2106.73665791776;2070.8574324291394;2111.820495257897;2119.057019626267;2121.416610432128;2123.5646692428013;2077.044559502631;2096.8008039236247;2116.968245476319;2117.3224568138203;2119.3112238522463;2013.2752209204675;2053.0761157479196;2053.2095143369834;2056.4653190170516;2060.9896950515226;1777.6711308407857;1828;2033.4103110098054;2039.5411032517713;2066.2561477867957;7266.164391095039;7389.1502477214735;7528.322450979384;7536;7558.773236802905;4419.204543182226;4579.152856721517;4601.378813860119;4603.9769801151115;4620.884477888046;2872.784541159421;2893.406851595439;2895.6235689360246;2944.8085874418093;2965.8220506769662;1001.5392919257077;1094.392612100284;1108.534415545473;1170.7834050700562;1171.7520840305049;8620.310375169971;8734.912650873537;8741.825163496758;8769;8790.077041910636;3633.7638053526434;3931.5478719947573;4019.7588144711412;4171.144915292358;4198.32826747725;8539.914600854036;8573.18554737299;8598.183172598594;8609.48343099416;8628.654853805778;4570.908581828411;4625.676202665781;4634.467036290802;4648.907021859612;4650.488446270908;4090.570490098497;4156.750594964312;4203.4115223868685;4240.575942405735;4253.21315556623;3515.156362472988;3538.292341531704;3616.9638303616625;3626.8730594429067;3630.8003059831435;1160.4545863444168;1191.839101721274;1199.5321824488456;1206.8612109607507;1215.2526196389333;680.7202459376341;722.7289766337624;730.8574827908614;737.5169264132002;774.7365895595525;4590.586847183769;4714.175019371596;4737.218358970731;4754.168020596451;4831.685940413862;908.2733812949614;925.9768505787274;941.091846368258;943.8961714211431;953.7853982853802;3228.6932741389533;3241.918952026171;3338.2655815720505;3393.694567488938;3471.288385880888;7264.200937896827;7517.924820751832;8244;8564.48613083217;8790.077041910507;8805.867911981326;8849;8861.025287218401;8864.290856731446;8865.556722163867;6756.864862702817;6931.757388491378;6971.442284617218;7023.297670233038;7053.188883278386;4908.92636610444;5025.572826309658;5076.873078172927;5098.974505127599;5152.845414637567;7777.494462859896;8009.91990080115;8043.959780201061;8139.186081391814;8170.959145204236;8035.156308587748;8206.22040906113;8265;8279.875801863098;8434.620442079999;6333.778317758856;6529.444997175293;6536.3136870629805;6540.967295163494;6548.246951600627;6981.581105133709;6990;7006.229314775473;7058.411857332059;7072.9646351767915;6257.843553911189;6328.683565821782;6334.271558770801;6547.083408322934;6547.541672083099;6218.782342617987;6296.968515157578;6314.589551679126;6391.360863913572;6502.349765023459;5410.945890541044;5497.835064948058;5534.08687566547;5534.91697624544;5541.307336582927;4130.876073717794;4152.1280531088805;4188.560201178832;4290.184864875664;4360.781960901879;3628.7822730636244;3752.212035472472;3842.442845787401;4106.856260030884;4259.936100958426 08:01:15 INFO - PROCESS | 32230 | |4;prototype.html;367.1701953584898;369.68022660398765;372.44505686527083;376.24187262665714;377.51489336202974;4953.380827396575;4980.402351717795;4981.007596961217;4998.450170481248;5018.347614810074;4700.670953033289;4943.6539442239055;4943.901121977558;4944.184209605411;4947.356843610334;3994.560598334181;3995.3607422812356;3995.820188091536;4003.4395184674167;4006.8597599084037;3980.8606698765548;3981.86063487778;3991.341428664267;3993.5008123984503;3998.3802510610903;3945.2109578084355;3959;3969.8412063517426;3983.1436241208135;3995.9200815983663;3589.407747721623;3761.586225515191;3803.410471376941;3962.128331767007;3972.033843247582;3302.190963214004;3311.271520265562;3317.2204531934976;3319.502074688816;3328.8335583220983;4764.951710623657;4777.5222477752295;4795.760211989386;4825.710457372569;4833.782479788383;4228.76741779202;4337.349397590355;4337.544557821415;4346.326319420495;4354.216241076605;5139.84580462583;5170.302009228743;5171.637985341028;5174.793008279665;5183.170692689152;3627.455881617752;3634.3821550336506;3658.5609726832686;3671.7429779915415;3682.3371793077235;5147.845564633029;5483.177523371486;5484.341878974548;5504.256925315071;5508.944910550883;3518.665726755954;3742.401215805458;3746.9625303746616;3747.45661879026;3752;3793.8482460701816;3806.5812760596314;3809.561900381492;3811.1424929390664;3826.5025546678753;4335.934960975588;4359;4365.301551751768;4367.868963931087;4384.824607015684;5021.548060674557;5174.482551744795;5187.247849061865;5192.701824543864;5211.583073354198;1274.133589159362;1282.4036822877406;1284.0882973089024;1288.8131220972907;1291.1930043722673;819.7786597618609;847.4915050969493;858.3519442820631;863.7538301584144;866.5883705239961;3459.68862802349;3841.846326146923;3915.47141135943;3936.5079365079364;4351.26028575143;4533.002739397327;4672;4684.906301873943;4701.741404222732;4848.248521479177;4438.356438316426;4449.555044495524;4465.151621191963;4475.9552404475535;4494.191045611756;2624.8687565621644;2703.7423751167603;2737.3156710822295;2744.7118052604587;2753.697093319733;4840.467548569725;4844.83723906267;4860.635452341018;4862.353235073695;4874.075554667172;5074.797008119708;5282.683039017595;5312.9810671944415;5318.547923426475;5336.519713225828;4805.591524720368;4853.223484242575;4857.931255123938;4865.391826021747;4869.513048695173;5179.559737422362;5196.896062078813;5199.792008319776;5204.505571970657;5218;4926.4037252806365;5100.413452453015;5122.257272695364;5131.8717032073;5147.382314122179;4805.543473370024;5004.499550044967;5027.874303142305;5038.193888977746;5067.493250674903;3657.140571965639;3697.4268988306853;3787.450819631193;3799.829007694605;3814.294355544233;5407.297051383295;5409.377921539073;5413.675179489243;5440.455954404528;5449.23710680513;4958.900821983612;5182.97408512955;5249.816256431007;5257.4742525747115;5268.1834315681135;5186.395455158779;5209.85816276069;5228.63399562027;5234.528892399702;5248.343957005374;5138.4604616514725;5162.664426812245;5164.790695583088;5180.145276029413;5207.531322181022;4974;5225.634205605571;5249.2651028855225;5253.219570191108;5270.341207349082;5131.281620573048;5135.229715542699;5137.537621614073;5142.508672485087;5170.48295170495 08:01:15 INFO - PROCESS | 32230 | |5;yui.html;650.122334847955;656.7668477690423;681.505908216543;698.8846840271352;712.3410844968405;12751.489940402384;13086.083974121815;13551.79672304915;13625.386857591408;13729.31353432328;13066.673333166651;13161.868381316184;13422.664433389145;13432.32838358083;13436.193828370304;797.7088362747585;818.8444195602846;819.4059307002435;819.9180081991783;820.6184124382161;713.2012146396017;717.4009701898921;783.6316931042401;807.0597753617029;809.3201710563126;744.4379493482413;748.1657951384194;749.7300971650202;750.89487471754;750.9624518774067;817.3297895725508;842.6081871929551;845.2688424512813;847.267113946438;852.0542198160038;549.1762356465302;621.9937800621981;624.3912185619031;624.9406306400884;630.9085182648536;11467.483963221675;12319.384030798514;12339.93830030844;12376.638569757319;12458.937705311506;11180.105591552656;12556.18384804994;12651.557195498206;12990.740185196242;12998.675033124153;10765.246432749711;10863.67408977732;10894.291871028357;10908.236423450362;10922.890771092267;3204.7436205103536;3244;3249.3501299740146;3285.2936618626873;3389.779664321811;57130.714346428416;58712;59380.70309648467;59793;60126.39873601252;9482.715518534455;9614;9779.706608801747;9830.361026533252;9869.358491698016;18510.352137675116;18724.81275187231;18766.624667506574;18768.155433005548;18779.530511737317;6456.741730330828;6487.967560162169;6492.610443373413;6532.934670653232;6566.835829104215;3545.663161999606;3651.9634803651625;3672.6510981457254;3678.834452449646;3721.292954338667;2805.8877644893973;2875.3099256178784;2889.4943384907556;2909.636295463067;2911.505044142501;489.87263311538544;497.52237851662085;498.6484528407464;498.99501004989486;507.092304774457;548.9725513724297;574.9145297030891;587.4213899309177;593.8693487432757;608.1311585640204;8430.873536896952;8468.745937621881;8517;8716.651333946698;9142.675582959715;549.543878580777;580.5558747558122;584.9151872978395;597.0715537339393;600.342624825815;3710.2023065040607;3768.9811550942068;4033.838646454109;4190;4233.491980962242;44986.30629910212;57196;57502;58441;59117.40882591205;53597.73201133969;53791.73104134532;54663.72668136714;55479.72260138673;56020.43979560234;49713.50286497161;50933.236001460005;51514;51973;52160;7500.68737970842;7809.453338266494;7885.454031285869;8014.118446970945;8094.51432914027;30423.54364684487;34683.30633387268;35097.82451087728;35553.288934220654;36873.44689829709;38298.23403531969;41932;43058.78470607627;43516.56483435125;43897.561024389346;10270.897291026995;10412.895871041193;11150.944245278723;11179.161562882653;11313.830292545454;11861.881381186078;12065.457054432396;12162.75674486523;13979.720405592034;14473.203973781543;11570.421478926188;11584.826227606423;12212.694682633006;12681.175723577939;12755.61733148007;12009.699757506132;12736.808947865604;12922.935385323013;12937.482500699867;13356.799648005093;11991.940040299743;12683.682907927006;12712.745745084861;12743.853053225253;12836.679083022998;8550.401471896908;8962.759310172456;9180.495072771062;9245.583948722458;9313.068693130905;82.02603058346129;8429.831403372022;8489.151084891462;8729.650813967624;9490.193333566724 08:01:15 INFO - PROCESS | 32230 | __end_tp_report 08:01:15 INFO - PROCESS | 32230 | __start_cc_report 08:01:15 INFO - PROCESS | 32230 | _x_x_mozilla_cycle_collect,392 08:01:15 INFO - PROCESS | 32230 | __end_cc_report 08:01:15 INFO - PROCESS | 32230 | __startTimestamp1487347275410__endTimestamp 08:01:15 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | ------- Summary: start ------- 08:01:15 INFO - PROCESS | 32230 | Number of tests: 6 08:01:15 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | [#0] dojo.html Cycles:165 Average:4620.92 Median:4719.41 stddev:479.85 (10.2%) stddev-sans-first:454.23 08:01:15 INFO - PROCESS | 32230 | Values: 2594.6 2621.4 2626.2 2641.1 2647.0 4696.3 4748.2 4774.6 4778.7 4814.2 4723.1 4745.8 4746.4 4750.5 4790.9 4114.4 4298.2 4368.2 4419.2 4542.4 4419.8 4435.8 4444.4 4486.4 4522.3 4304.8 4370.9 4371.5 4387.2 4424.6 4343.2 4361.8 4377.1 4382.2 4440.7 4305.8 4354.5 4376.6 4384.3 4417.7 4531.4 4558.7 4659.7 4670.8 4672.5 5037.9 5062.6 5063.6 5064.5 5089.9 5046.8 5061.2 5084.0 5087.5 5091.8 4589.7 4631.6 4691.6 4710.3 4745.8 4955.9 5169.7 5195.2 5226.7 5257.0 4773.6 4931.0 4955.8 5012.2 5086.0 3886.8 4513.3 4535.3 4573.2 5096.1 4379.5 4502.3 4556.1 4867.7 4875.0 4916.4 5008.8 5023.6 5076.8 5082.0 4336.4 4339.2 4342.1 4370.6 4370.7 3744.9 3756.1 3762.8 3794.3 3796.6 4314.9 4348.7 4350.3 4383.8 4478.9 5011.5 5013.2 5015.5 5048.7 5061.9 4379.3 4384.3 4394.3 4416.0 4428.8 4451.6 4472.6 4520.0 4523.9 4532.5 4222.7 4227.4 4254.6 4271.9 4690.7 4475.2 4477.5 4518.8 4924.6 5144.1 4693.1 4719.4 4790.8 4802.8 4810.2 4899.3 4936.9 4945.8 4958.7 4959.6 5001.1 5017.5 5052.4 5056.0 5119.7 4831.7 4863.9 4879.9 4896.5 4908.0 4868.1 4875.3 4875.9 4918.1 4924.1 4646.4 4769.2 4782.4 4794.2 4816.2 4748.8 4994.7 5005.2 5065.5 5123.4 4916.2 4949.1 4957.9 4964.5 5000.7 08:01:15 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | [#1] ext.html Cycles:180 Average:41877.16 Median:342348.50 stddev:73611.96 (21.5%) stddev-sans-first:73752.99 08:01:15 INFO - PROCESS | 32230 | Values: 529.1 539.3 547.9 550.2 561.1 32659.5 34379.5 34422.7 34450.5 34470.8 33026.8 34084.5 36921.0 38440.8 38675.8 7366.2 7663.8 7672.5 7701.2 9308.8 9296.0 9488.0 9546.6 9560.0 9560.8 9440.8 9501.8 9553.4 9564.3 9570.5 8426.2 8522.7 8531.7 8534.7 8563.4 8297.5 8347.4 9358.9 9379.1 9401.5 32161.8 32410.4 32490.8 32499.0 32562.5 3409.4 3483.5 3502.3 3532.3 3543.3 24804.0 24806.5 24861.4 25038.7 25359.6 3273.4 3344.0 3350.3 3370.5 3402.1 218767.0 219583.0 220020.7 221786.6 222509.0 38346.6 39607.8 46879.0 47014.8 47441.8 48712.5 49077.0 49440.0 54790.0 55279.7 11960.5 12024.8 12069.2 12069.2 12101.2 8675.8 8692.8 8694.0 8703.7 8720.7 15716.4 15748.7 15788.1 15793.0 15796.8 6424.9 6464.9 6634.1 6660.6 6818.6 745.4 755.7 759.7 764.0 773.7 26233.9 27176.9 27313.3 27425.7 27685.4 890.4 899.1 900.9 908.5 928.2 14283.4 14450.6 14500.5 14697.7 14709.8 341190.0 341583.0 343114.0 343348.0 343630.0 49244.0 49942.8 50163.0 50231.7 50246.7 1109.2 1139.3 1143.7 1146.9 1167.5 24758.9 25054.6 26677.0 26682.7 26749.0 40790.0 40809.8 41033.4 41129.4 41268.0 262855.0 263299.0 263326.0 265296.0 265328.0 21837.7 22981.8 23042.3 24025.9 24070.0 35741.3 35775.1 36242.5 36370.5 36913.3 30641.0 30787.7 30839.8 31307.1 31485.0 30351.7 31190.0 31474.7 31850.5 32252.0 29622.7 30052.4 30263.2 30346.4 30799.7 25958.2 26623.7 27930.0 28004.2 28218.4 21436.7 21527.0 21644.5 21682.5 21903.1 08:01:15 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | [#2] jquery.html Cycles:180 Average:25406.46 Median:4983.70 stddev:70920.84 (1423.1%) stddev-sans-first:71094.92 08:01:15 INFO - PROCESS | 32230 | Values: 389.9 397.7 399.2 402.7 421.3 17439.7 17768.4 18126.2 18158.0 18624.9 17423.6 17711.9 17767.3 17911.0 17920.9 5178.2 5195.5 5221.8 5385.6 5401.0 5262.2 5264.9 5282.8 5290.0 5308.3 5186.6 5205.9 5246.4 5261.5 5353.7 5486.7 5621.4 5641.8 5658.2 5663.9 4299.0 4930.4 5037.0 5546.0 5551.0 11147.9 11212.3 11222.0 11247.7 11272.2 2081.6 2154.3 2178.7 2186.0 2202.8 8848.9 9091.8 9523.8 9552.0 9640.7 1655.1 1661.9 1666.2 1695.2 1720.3 71462.6 74375.6 75803.0 76334.6 76401.6 9985.0 10018.7 10027.0 10397.9 10586.3 14690.9 14708.1 14709.0 14723.7 14803.8 6444.6 6743.5 6791.4 6905.5 6930.8 6122.5 6164.1 6346.3 6347.2 6370.8 4207.6 4254.3 4285.5 4381.8 4507.6 1858.8 1919.7 1922.7 1929.5 1940.8 792.9 828.0 831.3 832.1 834.8 16322.8 16824.3 17465.7 17599.5 18022.4 800.4 804.2 813.8 825.4 835.7 9180.2 9384.0 9600.0 9770.4 9821.4 422349.0 423903.0 424626.0 435550.0 442498.0 67320.0 69565.0 82907.0 82955.0 88892.6 923.3 953.4 970.5 972.8 977.7 16057.4 16164.7 16754.7 17000.0 17077.0 36531.6 38320.8 38692.4 39316.2 39998.4 46854.8 49294.3 50630.0 50883.0 51422.5 6834.6 6923.9 7042.9 7705.9 7827.7 7988.3 10754.8 10857.5 11000.4 12068.2 10012.5 10135.5 10396.3 10399.6 10597.1 10366.8 10433.6 10529.9 10722.5 10951.7 5899.4 8494.3 10529.5 10768.7 11053.5 7756.5 7838.2 7943.1 8001.2 8091.8 8965.4 9683.9 9952.3 9975.9 11403.8 08:01:15 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | [#3] mootools.html Cycles:180 Average:4784.19 Median:4967.25 stddev:2564.43 (51.6%) stddev-sans-first:2558.40 08:01:15 INFO - PROCESS | 32230 | Values: 1319.1 1341.2 1344.1 1349.3 1354.2 7045.7 7162.3 7358.3 7394.5 7464.7 7400.1 7531.9 7537.6 7539.7 7559.7 2058.1 2090.8 2100.1 2102.1 2106.7 2070.9 2111.8 2119.1 2121.4 2123.6 2077.0 2096.8 2117.0 2117.3 2119.3 2013.3 2053.1 2053.2 2056.5 2061.0 1777.7 1828.0 2033.4 2039.5 2066.3 7266.2 7389.2 7528.3 7536.0 7558.8 4419.2 4579.2 4601.4 4604.0 4620.9 2872.8 2893.4 2895.6 2944.8 2965.8 1001.5 1094.4 1108.5 1170.8 1171.8 8620.3 8734.9 8741.8 8769.0 8790.1 3633.8 3931.5 4019.8 4171.1 4198.3 8539.9 8573.2 8598.2 8609.5 8628.7 4570.9 4625.7 4634.5 4648.9 4650.5 4090.6 4156.8 4203.4 4240.6 4253.2 3515.2 3538.3 3617.0 3626.9 3630.8 1160.5 1191.8 1199.5 1206.9 1215.3 680.7 722.7 730.9 737.5 774.7 4590.6 4714.2 4737.2 4754.2 4831.7 908.3 926.0 941.1 943.9 953.8 3228.7 3241.9 3338.3 3393.7 3471.3 7264.2 7517.9 8244.0 8564.5 8790.1 8805.9 8849.0 8861.0 8864.3 8865.6 6756.9 6931.8 6971.4 7023.3 7053.2 4908.9 5025.6 5076.9 5099.0 5152.8 7777.5 8009.9 8044.0 8139.2 8171.0 8035.2 8206.2 8265.0 8279.9 8434.6 6333.8 6529.4 6536.3 6541.0 6548.2 6981.6 6990.0 7006.2 7058.4 7073.0 6257.8 6328.7 6334.3 6547.1 6547.5 6218.8 6297.0 6314.6 6391.4 6502.3 5410.9 5497.8 5534.1 5534.9 5541.3 4130.9 4152.1 4188.6 4290.2 4360.8 3628.8 3752.2 3842.4 4106.9 4259.9 08:01:15 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | [#4] prototype.html Cycles:180 Average:4249.56 Median:4829.75 stddev:1235.56 (25.6%) stddev-sans-first:1204.17 08:01:15 INFO - PROCESS | 32230 | Values: 367.2 369.7 372.4 376.2 377.5 4953.4 4980.4 4981.0 4998.5 5018.3 4700.7 4943.7 4943.9 4944.2 4947.4 3994.6 3995.4 3995.8 4003.4 4006.9 3980.9 3981.9 3991.3 3993.5 3998.4 3945.2 3959.0 3969.8 3983.1 3995.9 3589.4 3761.6 3803.4 3962.1 3972.0 3302.2 3311.3 3317.2 3319.5 3328.8 4765.0 4777.5 4795.8 4825.7 4833.8 4228.8 4337.3 4337.5 4346.3 4354.2 5139.8 5170.3 5171.6 5174.8 5183.2 3627.5 3634.4 3658.6 3671.7 3682.3 5147.8 5483.2 5484.3 5504.3 5508.9 3518.7 3742.4 3747.0 3747.5 3752.0 3793.8 3806.6 3809.6 3811.1 3826.5 4335.9 4359.0 4365.3 4367.9 4384.8 5021.5 5174.5 5187.2 5192.7 5211.6 1274.1 1282.4 1284.1 1288.8 1291.2 819.8 847.5 858.4 863.8 866.6 3459.7 3841.8 3915.5 3936.5 4351.3 4533.0 4672.0 4684.9 4701.7 4848.2 4438.4 4449.6 4465.2 4476.0 4494.2 2624.9 2703.7 2737.3 2744.7 2753.7 4840.5 4844.8 4860.6 4862.4 4874.1 5074.8 5282.7 5313.0 5318.5 5336.5 4805.6 4853.2 4857.9 4865.4 4869.5 5179.6 5196.9 5199.8 5204.5 5218.0 4926.4 5100.4 5122.3 5131.9 5147.4 4805.5 5004.5 5027.9 5038.2 5067.5 3657.1 3697.4 3787.5 3799.8 3814.3 5407.3 5409.4 5413.7 5440.5 5449.2 4958.9 5183.0 5249.8 5257.5 5268.2 5186.4 5209.9 5228.6 5234.5 5248.3 5138.5 5162.7 5164.8 5180.1 5207.5 4974.0 5225.6 5249.3 5253.2 5270.3 5131.3 5135.2 5137.5 5142.5 5170.5 08:01:15 INFO - PROCESS | 32230 | 08:01:15 INFO - PROCESS | 32230 | [#5] yui.html Cycles:180 Average:14113.59 Median:503.04 stddev:17020.63 (3383.5%) stddev-sans-first:17038.35 08:01:15 INFO - PROCESS | 32230 | Values: 650.1 656.8 681.5 698.9 712.3 12751.5 13086.1 13551.8 13625.4 13729.3 13066.7 13161.9 13422.7 13432.3 13436.2 797.7 818.8 819.4 819.9 820.6 713.2 717.4 783.6 807.1 809.3 744.4 748.2 749.7 750.9 751.0 817.3 842.6 845.3 847.3 852.1 549.2 622.0 624.4 624.9 630.9 11467.5 12319.4 12339.9 12376.6 12458.9 11180.1 12556.2 12651.6 12990.7 12998.7 10765.2 10863.7 10894.3 10908.2 10922.9 3204.7 3244.0 3249.4 3285.3 3389.8 57130.7 58712.0 59380.7 59793.0 60126.4 9482.7 9614.0 9779.7 9830.4 9869.4 18510.4 18724.8 18766.6 18768.2 18779.5 6456.7 6488.0 6492.6 6532.9 6566.8 3545.7 3652.0 3672.7 3678.8 3721.3 2805.9 2875.3 2889.5 2909.6 2911.5 489.9 497.5 498.6 499.0 507.1 549.0 574.9 587.4 593.9 608.1 8430.9 8468.7 8517.0 8716.7 9142.7 549.5 580.6 584.9 597.1 600.3 3710.2 3769.0 4033.8 4190.0 4233.5 44986.3 57196.0 57502.0 58441.0 59117.4 53597.7 53791.7 54663.7 55479.7 56020.4 49713.5 50933.2 51514.0 51973.0 52160.0 7500.7 7809.5 7885.5 8014.1 8094.5 30423.5 34683.3 35097.8 35553.3 36873.4 38298.2 41932.0 43058.8 43516.6 43897.6 10270.9 10412.9 11150.9 11179.2 11313.8 11861.9 12065.5 12162.8 13979.7 14473.2 11570.4 11584.8 12212.7 12681.2 12755.6 12009.7 12736.8 12922.9 12937.5 13356.8 11991.9 12683.7 12712.7 12743.9 12836.7 8550.4 8962.8 9180.5 9245.6 9313.1 82.0 8429.8 8489.2 8729.7 9490.2 08:01:15 INFO - PROCESS | 32230 | -------- Summary: end -------- 08:01:15 INFO - PROCESS | 32230 | 08:01:16 INFO - TEST-INFO | 32230: exit 0 08:01:16 INFO - TEST-OK | dromaeo_css | took 1131971ms 08:01:16 INFO - TEST-START | kraken 08:01:16 INFO - operating with platform_type : linux_ 08:01:16 INFO - Initialising browser for kraken test... 08:01:16 INFO - TEST-INFO | started process 32609 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgE9VbJ/profile http://localhost:56803/getInfo.html) 08:01:19 INFO - PROCESS | 32609 | Iterating over profile /tmp/tmpgE9VbJ/profile 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/addons.json = Fri Feb 17 2017 08:01:18 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/crashes = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/extensions.ini = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/cache2 = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/sessionCheckpoints.json = Fri Feb 17 2017 08:01:20 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/minidumps = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/permissions.sqlite = Fri Feb 17 2017 08:01:17 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/key3.db = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/prefs.js = Fri Feb 17 2017 07:41:43 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | Stat failure 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/thumbnails = Fri Feb 17 2017 08:01:18 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/cert8.db = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/pluginreg.dat = Fri Feb 17 2017 08:01:18 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/blocklist.xml = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/.parentlock = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/gmp = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/mimeTypes.rdf = Fri Feb 17 2017 08:01:19 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/compatibility.ini = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/places.sqlite = Fri Feb 17 2017 08:01:20 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/content-prefs.sqlite = Fri Feb 17 2017 08:01:18 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/frequencyCap.json = Fri Feb 17 2017 08:01:18 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/secmod.db = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/bookmarkbackups = Fri Feb 17 2017 08:01:18 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/user.js = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | __metrics Screen width/height:1600/1200 08:01:20 INFO - PROCESS | 32609 | colorDepth:24 08:01:20 INFO - PROCESS | 32609 | Browser inner width/height: 1024/697 08:01:20 INFO - PROCESS | 32609 | __metrics 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/places.sqlite-wal = Fri Feb 17 2017 08:01:20 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/directoryLinks.json = Fri Feb 17 2017 08:01:18 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/safebrowsing = Fri Feb 17 2017 08:01:20 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/startupCache = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/extensions.json = Fri Feb 17 2017 08:01:18 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/localstore.rdf = Fri Feb 17 2017 07:41:43 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/places.sqlite-shm = Fri Feb 17 2017 08:01:17 GMT-0800 (PST) 08:01:20 INFO - PROCESS | 32609 | No birth date. Using mtime. 08:01:20 INFO - PROCESS | 32609 | Using date: /tmp/tmpgE9VbJ/profile/extensions = Fri Feb 17 2017 08:01:16 GMT-0800 (PST) 08:01:21 ERROR - PROCESS | 32609 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 08:01:22 INFO - TEST-INFO | 32609: exit 0 08:01:22 INFO - Browser initialized. 08:01:22 INFO - Running cycle 1/1 for kraken test... 08:01:22 INFO - TEST-INFO | started process 32736 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgE9VbJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:38 INFO - PROCESS | 32736 | RSS: Main: 156704768 08:01:38 INFO - PROCESS | 32736 | 08:01:41 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56803/tests/kraken/driver.html?testName=audio-beat-detection) 08:01:42 INFO - PROCESS | 32736 | RSS: Main: 161693696 08:01:42 INFO - PROCESS | 32736 | 08:01:45 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56803/tests/kraken/driver.html?testName=audio-dft) 08:01:46 INFO - PROCESS | 32736 | RSS: Main: 155512832 08:01:46 INFO - PROCESS | 32736 | 08:01:52 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56803/tests/kraken/driver.html?testName=audio-fft) 08:01:52 INFO - PROCESS | 32736 | RSS: Main: 156364800 08:01:52 INFO - PROCESS | 32736 | 08:01:54 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56803/tests/kraken/driver.html?testName=audio-oscillator) 08:01:55 INFO - PROCESS | 32736 | RSS: Main: 160542720 08:01:55 INFO - PROCESS | 32736 | 08:01:57 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56803/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:01:58 INFO - PROCESS | 32736 | RSS: Main: 160161792 08:01:58 INFO - PROCESS | 32736 | 08:02:02 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56803/tests/kraken/driver.html?testName=imaging-darkroom) 08:02:02 INFO - PROCESS | 32736 | RSS: Main: 157704192 08:02:02 INFO - PROCESS | 32736 | 08:02:06 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56803/tests/kraken/driver.html?testName=imaging-desaturate) 08:02:08 INFO - PROCESS | 32736 | RSS: Main: 157515776 08:02:08 INFO - PROCESS | 32736 | 08:02:11 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56803/tests/kraken/driver.html?testName=json-parse-financial) 08:02:11 INFO - PROCESS | 32736 | RSS: Main: 159494144 08:02:11 INFO - PROCESS | 32736 | 08:02:13 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56803/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:02:14 INFO - PROCESS | 32736 | RSS: Main: 159150080 08:02:14 INFO - PROCESS | 32736 | 08:02:15 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56803/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:02:16 INFO - PROCESS | 32736 | RSS: Main: 158838784 08:02:16 INFO - PROCESS | 32736 | 08:02:18 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56803/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:02:19 INFO - PROCESS | 32736 | RSS: Main: 159051776 08:02:19 INFO - PROCESS | 32736 | 08:02:22 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56803/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:02:23 INFO - PROCESS | 32736 | RSS: Main: 173326336 08:02:23 INFO - PROCESS | 32736 | 08:02:26 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56803/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:02:27 INFO - PROCESS | 32736 | RSS: Main: 165130240 08:02:27 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | Cycle 1(1): loaded http://localhost:56803/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56803/tests/kraken/driver.html?testName=ai-astar) 08:02:29 INFO - PROCESS | 32736 | RSS: Main: 163078144 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | __start_tp_report 08:02:29 INFO - PROCESS | 32736 | _x_x_mozilla_page_load 08:02:29 INFO - PROCESS | 32736 | _x_x_mozilla_page_load_details 08:02:29 INFO - PROCESS | 32736 | |i|pagename|runs| 08:02:29 INFO - PROCESS | 32736 | |0;ai-astar;110;116;116;115;116;116;116;116;116;116 08:02:29 INFO - PROCESS | 32736 | |1;audio-beat-detection;157;131;129;181;133;146;143;144;163;147 08:02:29 INFO - PROCESS | 32736 | |2;audio-dft;181;182;183;181;182;184;183;183;183;182 08:02:29 INFO - PROCESS | 32736 | |3;audio-fft;76;78;77;104;77;78;76;76;91;77 08:02:29 INFO - PROCESS | 32736 | |4;audio-oscillator;85;83;108;85;89;85;105;85;87;101 08:02:29 INFO - PROCESS | 32736 | |5;imaging-gaussian-blur;114;114;114;117;131;131;130;130;130;130 08:02:29 INFO - PROCESS | 32736 | |6;imaging-darkroom;173;172;173;171;172;172;172;172;171;170 08:02:29 INFO - PROCESS | 32736 | |7;imaging-desaturate;97;98;95;97;97;96;96;96;97;96 08:02:29 INFO - PROCESS | 32736 | |8;json-parse-financial;66;65;65;65;66;68;67;68;66;66 08:02:29 INFO - PROCESS | 32736 | |9;json-stringify-tinderbox;66;66;66;67;66;67;66;66;66;66 08:02:29 INFO - PROCESS | 32736 | |10;stanford-crypto-aes;87;90;89;87;95;88;94;89;88;87 08:02:29 INFO - PROCESS | 32736 | |11;stanford-crypto-ccm;156;148;156;150;150;150;139;147;194;159 08:02:29 INFO - PROCESS | 32736 | |12;stanford-crypto-pbkdf2;184;183;181;187;183;217;203;203;208;204 08:02:29 INFO - PROCESS | 32736 | |13;stanford-crypto-sha256-iterative;88;83;83;76;81;82;77;77;82;76 08:02:29 INFO - PROCESS | 32736 | __end_tp_report 08:02:29 INFO - PROCESS | 32736 | __start_cc_report 08:02:29 INFO - PROCESS | 32736 | _x_x_mozilla_cycle_collect,858 08:02:29 INFO - PROCESS | 32736 | __end_cc_report 08:02:29 INFO - PROCESS | 32736 | __startTimestamp1487347349717__endTimestamp 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | ------- Summary: start ------- 08:02:29 INFO - PROCESS | 32736 | Number of tests: 14 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#0] ai-astar Cycles:10 Average:115.30 Median:116.00 stddev:1.89 (1.6%) stddev-sans-first:0.33 08:02:29 INFO - PROCESS | 32736 | Values: 110.0 116.0 116.0 115.0 116.0 116.0 116.0 116.0 116.0 116.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#1] audio-beat-detection Cycles:10 Average:147.40 Median:146.50 stddev:16.03 (10.9%) stddev-sans-first:16.62 08:02:29 INFO - PROCESS | 32736 | Values: 157.0 131.0 129.0 181.0 133.0 146.0 143.0 144.0 163.0 147.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#2] audio-dft Cycles:10 Average:182.40 Median:183.00 stddev:0.97 (0.5%) stddev-sans-first:0.88 08:02:29 INFO - PROCESS | 32736 | Values: 181.0 182.0 183.0 181.0 182.0 184.0 183.0 183.0 183.0 182.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#3] audio-fft Cycles:10 Average:81.00 Median:77.00 stddev:9.25 (12.0%) stddev-sans-first:9.63 08:02:29 INFO - PROCESS | 32736 | Values: 76.0 78.0 77.0 104.0 77.0 78.0 76.0 76.0 91.0 77.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:85.00 stddev:9.50 (11.2%) stddev-sans-first:9.80 08:02:29 INFO - PROCESS | 32736 | Values: 85.0 83.0 108.0 85.0 89.0 85.0 105.0 85.0 87.0 101.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#5] imaging-gaussian-blur Cycles:10 Average:124.10 Median:130.00 stddev:8.10 (6.2%) stddev-sans-first:7.73 08:02:29 INFO - PROCESS | 32736 | Values: 114.0 114.0 114.0 117.0 131.0 131.0 130.0 130.0 130.0 130.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#6] imaging-darkroom Cycles:10 Average:171.80 Median:172.00 stddev:0.92 (0.5%) stddev-sans-first:0.87 08:02:29 INFO - PROCESS | 32736 | Values: 173.0 172.0 173.0 171.0 172.0 172.0 172.0 172.0 171.0 170.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:97.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 08:02:29 INFO - PROCESS | 32736 | Values: 97.0 98.0 95.0 97.0 97.0 96.0 96.0 96.0 97.0 96.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:66.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 08:02:29 INFO - PROCESS | 32736 | Values: 66.0 65.0 65.0 65.0 66.0 68.0 67.0 68.0 66.0 66.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 08:02:29 INFO - PROCESS | 32736 | Values: 66.0 66.0 66.0 67.0 66.0 67.0 66.0 66.0 66.0 66.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:89.00 stddev:2.88 (3.2%) stddev-sans-first:2.92 08:02:29 INFO - PROCESS | 32736 | Values: 87.0 90.0 89.0 87.0 95.0 88.0 94.0 89.0 88.0 87.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:153.00 stddev:14.84 (9.7%) stddev-sans-first:15.74 08:02:29 INFO - PROCESS | 32736 | Values: 156.0 148.0 156.0 150.0 150.0 150.0 139.0 147.0 194.0 159.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.30 Median:203.00 stddev:13.04 (6.4%) stddev-sans-first:13.17 08:02:29 INFO - PROCESS | 32736 | Values: 184.0 183.0 181.0 187.0 183.0 217.0 203.0 203.0 208.0 204.0 08:02:29 INFO - PROCESS | 32736 | 08:02:29 INFO - PROCESS | 32736 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.50 Median:82.00 stddev:3.92 (4.8%) stddev-sans-first:3.08 08:02:29 INFO - PROCESS | 32736 | Values: 88.0 83.0 83.0 76.0 81.0 82.0 77.0 77.0 82.0 76.0 08:02:29 INFO - PROCESS | 32736 | -------- Summary: end -------- 08:02:29 INFO - PROCESS | 32736 | 08:02:33 INFO - TEST-INFO | 32736: exit 0 08:02:34 INFO - TEST-OK | kraken | took 77697ms 08:02:34 INFO - SUITE-END | took 1209s 08:02:34 INFO - Completed test suite (00:20:10) 08:02:34 INFO - javascript benchmark 08:02:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6193.663000282341, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2594.623779551965, 2621.4494956059225, 2626.1858823764624, 2641.0624228398906, 2647.047063057299, 4696.342512048307, 4748.192807222772, 4774.594159496442, 4778.71327720337, 4814.1575224335775, 4723.055388922212, 4745.762711864401, 4746.406699162605, 4750.501197374278, 4790.904181916359, 4114.362273847543, 4298.204832106055, 4368.235558777217, 4419.160359531695, 4542.386777785006, 4419.801108950105, 4435.822567097296, 4444.400005999198, 4486.371907932892, 4522.299043648223, 4304.7632380219075, 4370.8907277318, 4371.540988196257, 4387.210302145612, 4424.646028317727, 4343.153085148398, 4361.760103194323, 4377.080813029268, 4382.249494873912, 4440.666949978732, 4305.827766889321, 4354.542773008852, 4376.5623437656295, 4384.298512238027, 4417.712848664826, 4531.410916580857, 4558.749268790215, 4659.673822832403, 4670.766461676936, 4672.462666793294, 5037.949620503785, 5062.594992400599, 5063.569596584295, 5064.468230835783, 5089.898202035975, 5046.84859454217, 5061.240813877911, 5084.0, 5087.516685914869, 5091.847244582631, 4589.655775816827, 4631.606313463326, 4691.554302341272, 4710.340552322609, 4745.762711864393, 4955.925661115086, 5169.689818610896, 5195.246689230041, 5226.686398816083, 5257.0, 4773.570378665937, 4930.975345123252, 4955.776990035457, 5012.1980483122525, 5086.0, 3886.7862267574988, 4513.345564893072, 4535.251683472256, 4573.176828170961, 5096.108181068298, 4379.4525684289465, 4502.3246513023305, 4556.066006468666, 4867.659263851497, 4874.951250487521, 4916.410030796327, 5008.84973450797, 5023.648344615842, 5076.796928122908, 5081.983603279359, 4336.4145840311785, 4339.240632889232, 4342.109867477159, 4370.606645401929, 4370.672199585044, 3744.9063773405883, 3756.0609847538117, 3762.793046382475, 3794.317022935898, 3796.6393192646656, 4314.913701725948, 4348.717333373384, 4350.347447882843, 4383.846565370197, 4478.932816007762, 5011.498850114959, 5013.248012798036, 5015.473375295542, 5048.7475626219275, 5061.949380506221, 4379.343098535245, 4384.254676704968, 4394.2749446340995, 4415.955840441555, 4428.756418396954, 4451.554844515587, 4472.619827314649, 4520.0, 4523.88690282739, 4532.456105267389, 4222.683298752544, 4227.365895115634, 4254.553271906529, 4271.893202669834, 4690.671652984258, 4475.172093162775, 4477.462704475483, 4518.841840535697, 4924.556789888928, 5144.074066668036, 4693.084848454519, 4719.4100737407825, 4790.760461976817, 4802.759862006956, 4810.230363141881, 4899.265110233351, 4936.901261974812, 4945.777440015136, 4958.677685950402, 4959.60323174138, 5001.124803159505, 5017.548420642013, 5052.393712754493, 5056.039352523009, 5119.692818430757, 4831.661783675249, 4863.927041094457, 4879.926801098058, 4896.485868983706, 4908.0, 4868.075065737498, 4875.317455556154, 4875.902481950412, 4918.0901533216465, 4924.113659541317, 4646.3727396801, 4769.165396055746, 4782.426108866958, 4794.184988552024, 4816.229403295456, 4748.833790817304, 4994.675346102637, 5005.224190250517, 5065.468125846733, 5123.38519377677, 4916.164252077227, 4949.133901567139, 4957.900841983213, 4964.453910069961, 5000.67495612784], "value": 4591.823473312372, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.1480716047164, 539.2719828231885, 547.879466517366, 550.1857251268123, 561.0798290803084, 32659.510107348407, 34379.48430773537, 34422.65577344226, 34450.483242751376, 34470.8276458618, 33026.83486582564, 34084.48873266903, 36921.0, 38440.80779596105, 38675.806620966854, 7366.226546212651, 7663.808404789897, 7672.539647621133, 7701.229877012282, 9308.767280818, 9296.023917488668, 9487.952560237189, 9546.570404331787, 9560.0, 9560.760980975461, 9440.763980900498, 9501.80996380072, 9553.379030363005, 9564.330496865223, 9570.47362395068, 8426.241638252555, 8522.74431767048, 8531.701390451335, 8534.743957681218, 8563.400561960665, 8297.460665056751, 8347.373946954003, 9358.906410935871, 9379.108984646447, 9401.482918439484, 32161.839190803898, 32410.351792964244, 32490.837545812356, 32499.0, 32562.511562326585, 3409.4203985322556, 3483.477478378238, 3502.3345564342694, 3532.293541291752, 3543.3090547343277, 24804.0, 24806.5038699225, 24861.378465538324, 25038.749612504005, 25359.619605705928, 3273.3548911857497, 3343.9665603343897, 3350.279689866666, 3370.5186406667585, 3402.0984439123654, 218767.0, 219583.0, 220020.69968950478, 221786.5838720478, 222509.0, 38346.616533834866, 39607.80196099001, 46879.0, 47014.76492617515, 47441.76279118582, 48712.51287487151, 49077.0, 49440.0, 54790.0, 55279.72360138174, 11960.521579136737, 12024.819627705592, 12069.15515913895, 12069.215500992408, 12101.213421127599, 8675.783105422288, 8692.78268043304, 8694.0, 8703.6518539259, 8720.651173953098, 15716.371345146295, 15748.68502629941, 15788.131652759208, 15793.0, 15796.763048554281, 6424.935750642528, 6464.935350646528, 6634.13756211699, 6660.6336651484635, 6818.590884546844, 745.4409193105171, 755.7279379423403, 759.7227012140529, 763.969441222356, 773.6595897804948, 26233.868830655723, 27176.864115679295, 27313.31716707098, 27425.725742742718, 27685.446291074066, 890.3500444675424, 899.0874262623443, 900.8558630619201, 908.5366463103733, 928.1785619726455, 14283.357248923823, 14450.566483005527, 14500.492482763053, 14697.706045879022, 14709.779353309708, 341190.0, 341583.0, 343114.0, 343348.0, 343630.0, 49244.0, 49942.750286249066, 50163.0, 50231.74884125556, 50246.748766256664, 1109.1847492093304, 1139.2537887683582, 1143.7140714821294, 1146.9483873225558, 1167.4629670351733, 24758.876205618857, 25054.62418063767, 26677.0, 26682.733172668803, 26749.0, 40790.0, 40809.79595102005, 41033.384499233136, 41129.38305925473, 41268.0, 262855.0, 263299.0, 263326.0, 265296.0, 265328.0, 21837.672434913173, 22981.77018229796, 23042.308730738103, 24025.879870600536, 24070.0, 35741.285174296885, 35775.105622359646, 36242.456363154044, 36370.4544431839, 36913.26173476569, 30641.0, 30787.692123079378, 30839.845800770854, 31307.07069706118, 31485.0, 30351.696483034888, 31190.0, 31474.685253147174, 31850.522242165924, 32252.0, 29622.703772961995, 30052.3989520204, 30263.243418914702, 30346.393072138875, 30799.692003080578, 25958.22125336243, 26623.733762662127, 27930.0, 28004.159875203775, 28218.435631286848, 21436.678449823576, 21527.0, 21644.458888527282, 21682.457938551663, 21903.12387504482], "value": 16279.66573361119, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.8830350894731, 397.7394806401808, 399.19960479239137, 402.6899287548588, 421.2916623093357, 17439.738403923937, 17768.378106766264, 18126.18432170552, 18157.99999999998, 18624.906875465636, 17423.564410889736, 17711.91144044278, 17767.289308427647, 17911.0, 17920.910395448005, 5178.223266510016, 5195.532402083812, 5221.765020574083, 5385.623006389555, 5401.027814993299, 5262.184361423966, 5264.92102618461, 5282.788688452457, 5290.0, 5308.336457942757, 5186.636935414523, 5205.9219111713355, 5246.37043554772, 5261.473852614727, 5353.732313384335, 5486.725663716818, 5621.353544342412, 5641.802536911188, 5658.207850900877, 5663.886722265532, 4299.0, 4930.4330002049865, 5036.974815125937, 5546.0, 5550.972245138749, 11147.888521114848, 11212.27120237182, 11222.0, 11247.718807029889, 11272.210945233837, 2081.5628717969244, 2154.256781410411, 2178.7167668203033, 2185.967210491844, 2202.812760915324, 8848.911510884873, 9091.818163636757, 9523.761905952337, 9551.952240238823, 9640.662576809778, 1655.114513735158, 1661.9335226591043, 1666.166916541729, 1695.1693670101686, 1720.3118752498976, 71462.64268678623, 74375.62812185958, 75803.0, 76334.61832690856, 76401.61799191024, 9985.004498650376, 10018.699439016842, 10027.0, 10397.896021039844, 10586.311889727298, 14690.853091469162, 14708.117512949044, 14709.0, 14723.705525889636, 14803.777943330859, 6444.6133232005295, 6743.494237932175, 6791.388775010174, 6905.482088843357, 6930.757423490154, 6122.54080943931, 6164.075388691643, 6346.27017892939, 6347.206599175103, 6370.77702280418, 4207.600277973587, 4254.319308910622, 4285.485741710952, 4381.78091095444, 4507.57178068083, 1858.7676540432446, 1919.7120431935043, 1922.6731455652573, 1929.4693959161343, 1940.805919408076, 792.9326007289329, 827.9710210142737, 831.3307787231216, 832.0930186097183, 834.7579202031343, 16322.83677163237, 16824.327026918785, 17465.7380139298, 17599.47201583929, 18022.3692170776, 800.371708209053, 804.2480280937342, 813.7762115418308, 825.3727167352855, 835.7242110103651, 9180.21968132703, 9384.0, 9599.984003359234, 9770.413775173512, 9821.410715357102, 422349.0, 423903.0, 424626.0, 435550.0, 442498.0, 67320.0, 69564.95652565216, 82907.0, 82955.0, 88892.5555372219, 923.2936803345447, 953.4231789767091, 970.4904924914476, 972.7665360313474, 977.6822532676908, 16057.357705691642, 16164.676706465569, 16754.66490670204, 17000.0, 17077.0, 36531.634683652825, 38320.80839595784, 38692.41961370526, 39316.213675726896, 39998.40002400024, 46854.76572617252, 49294.26058609052, 50630.0, 50883.0, 51422.48577514177, 6834.589924604539, 6923.861522769617, 7042.859142817218, 7705.88441173394, 7827.686892524235, 7988.281054705104, 10754.838677419688, 10857.457127143769, 11000.394978276272, 12068.155229133876, 10012.549435275285, 10135.54390052464, 10396.324238924446, 10399.584016639552, 10597.07078519917, 10366.844497332697, 10433.582656693647, 10529.894701052892, 10722.51748671296, 10951.671449856518, 5899.380565040609, 8494.320454363513, 10529.473526323807, 10768.676939691823, 11053.502592383202, 7756.534607923542, 7838.216178382117, 7943.126256111707, 8001.159878212704, 8091.797705057185, 8965.372423930263, 9683.903160968583, 9952.303338766507, 9975.850362244428, 11403.828942566033], "value": 8127.349500305625, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.122783349073, 1341.1684755451608, 1344.1128854955732, 1349.2511656030902, 1354.1942544186209, 7045.682944267507, 7162.283771622835, 7358.264173582639, 7394.519356241847, 7464.664090115951, 7400.074990626172, 7531.887021694679, 7537.585432801194, 7539.698412063524, 7559.7354092606765, 2058.0738667599567, 2090.80137386948, 2100.1284466946277, 2102.1171108134545, 2106.73665791776, 2070.8574324291394, 2111.820495257897, 2119.057019626267, 2121.416610432128, 2123.5646692428013, 2077.044559502631, 2096.8008039236247, 2116.968245476319, 2117.3224568138203, 2119.3112238522463, 2013.2752209204675, 2053.0761157479196, 2053.2095143369834, 2056.4653190170516, 2060.9896950515226, 1777.6711308407857, 1828.0, 2033.4103110098054, 2039.5411032517713, 2066.2561477867957, 7266.164391095039, 7389.1502477214735, 7528.322450979384, 7536.0, 7558.773236802905, 4419.204543182226, 4579.152856721517, 4601.378813860119, 4603.9769801151115, 4620.884477888046, 2872.784541159421, 2893.406851595439, 2895.6235689360246, 2944.8085874418093, 2965.8220506769662, 1001.5392919257077, 1094.392612100284, 1108.534415545473, 1170.7834050700562, 1171.7520840305049, 8620.310375169971, 8734.912650873537, 8741.825163496758, 8769.0, 8790.077041910636, 3633.7638053526434, 3931.5478719947573, 4019.7588144711412, 4171.144915292358, 4198.32826747725, 8539.914600854036, 8573.18554737299, 8598.183172598594, 8609.48343099416, 8628.654853805778, 4570.908581828411, 4625.676202665781, 4634.467036290802, 4648.907021859612, 4650.488446270908, 4090.570490098497, 4156.750594964312, 4203.4115223868685, 4240.575942405735, 4253.21315556623, 3515.156362472988, 3538.292341531704, 3616.9638303616625, 3626.8730594429067, 3630.8003059831435, 1160.4545863444168, 1191.839101721274, 1199.5321824488456, 1206.8612109607507, 1215.2526196389333, 680.7202459376341, 722.7289766337624, 730.8574827908614, 737.5169264132002, 774.7365895595525, 4590.586847183769, 4714.175019371596, 4737.218358970731, 4754.168020596451, 4831.685940413862, 908.2733812949614, 925.9768505787274, 941.091846368258, 943.8961714211431, 953.7853982853802, 3228.6932741389533, 3241.918952026171, 3338.2655815720505, 3393.694567488938, 3471.288385880888, 7264.200937896827, 7517.924820751832, 8244.0, 8564.48613083217, 8790.077041910507, 8805.867911981326, 8849.0, 8861.025287218401, 8864.290856731446, 8865.556722163867, 6756.864862702817, 6931.757388491378, 6971.442284617218, 7023.297670233038, 7053.188883278386, 4908.92636610444, 5025.572826309658, 5076.873078172927, 5098.974505127599, 5152.845414637567, 7777.494462859896, 8009.91990080115, 8043.959780201061, 8139.186081391814, 8170.959145204236, 8035.156308587748, 8206.22040906113, 8265.0, 8279.875801863098, 8434.620442079999, 6333.778317758856, 6529.444997175293, 6536.3136870629805, 6540.967295163494, 6548.246951600627, 6981.581105133709, 6990.0, 7006.229314775473, 7058.411857332059, 7072.9646351767915, 6257.843553911189, 6328.683565821782, 6334.271558770801, 6547.083408322934, 6547.541672083099, 6218.782342617987, 6296.968515157578, 6314.589551679126, 6391.360863913572, 6502.349765023459, 5410.945890541044, 5497.835064948058, 5534.08687566547, 5534.91697624544, 5541.307336582927, 4130.876073717794, 4152.1280531088805, 4188.560201178832, 4290.184864875664, 4360.781960901879, 3628.7822730636244, 3752.212035472472, 3842.442845787401, 4106.856260030884, 4259.936100958426], "value": 3918.3851567055876, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.1701953584898, 369.68022660398765, 372.44505686527083, 376.24187262665714, 377.51489336202974, 4953.380827396575, 4980.402351717795, 4981.007596961217, 4998.450170481248, 5018.347614810074, 4700.670953033289, 4943.6539442239055, 4943.901121977558, 4944.184209605411, 4947.356843610334, 3994.560598334181, 3995.3607422812356, 3995.820188091536, 4003.4395184674167, 4006.8597599084037, 3980.8606698765548, 3981.86063487778, 3991.341428664267, 3993.5008123984503, 3998.3802510610903, 3945.2109578084355, 3959.0, 3969.8412063517426, 3983.1436241208135, 3995.9200815983663, 3589.407747721623, 3761.586225515191, 3803.410471376941, 3962.128331767007, 3972.033843247582, 3302.190963214004, 3311.271520265562, 3317.2204531934976, 3319.502074688816, 3328.8335583220983, 4764.951710623657, 4777.5222477752295, 4795.760211989386, 4825.710457372569, 4833.782479788383, 4228.76741779202, 4337.349397590355, 4337.544557821415, 4346.326319420495, 4354.216241076605, 5139.84580462583, 5170.302009228743, 5171.637985341028, 5174.793008279665, 5183.170692689152, 3627.455881617752, 3634.3821550336506, 3658.5609726832686, 3671.7429779915415, 3682.3371793077235, 5147.845564633029, 5483.177523371486, 5484.341878974548, 5504.256925315071, 5508.944910550883, 3518.665726755954, 3742.401215805458, 3746.9625303746616, 3747.45661879026, 3752.0, 3793.8482460701816, 3806.5812760596314, 3809.561900381492, 3811.1424929390664, 3826.5025546678753, 4335.934960975588, 4359.0, 4365.301551751768, 4367.868963931087, 4384.824607015684, 5021.548060674557, 5174.482551744795, 5187.247849061865, 5192.701824543864, 5211.583073354198, 1274.133589159362, 1282.4036822877406, 1284.0882973089024, 1288.8131220972907, 1291.1930043722673, 819.7786597618609, 847.4915050969493, 858.3519442820631, 863.7538301584144, 866.5883705239961, 3459.68862802349, 3841.846326146923, 3915.47141135943, 3936.5079365079364, 4351.26028575143, 4533.002739397327, 4672.0, 4684.906301873943, 4701.741404222732, 4848.248521479177, 4438.356438316426, 4449.555044495524, 4465.151621191963, 4475.9552404475535, 4494.191045611756, 2624.8687565621644, 2703.7423751167603, 2737.3156710822295, 2744.7118052604587, 2753.697093319733, 4840.467548569725, 4844.83723906267, 4860.635452341018, 4862.353235073695, 4874.075554667172, 5074.797008119708, 5282.683039017595, 5312.9810671944415, 5318.547923426475, 5336.519713225828, 4805.591524720368, 4853.223484242575, 4857.931255123938, 4865.391826021747, 4869.513048695173, 5179.559737422362, 5196.896062078813, 5199.792008319776, 5204.505571970657, 5218.0, 4926.4037252806365, 5100.413452453015, 5122.257272695364, 5131.8717032073, 5147.382314122179, 4805.543473370024, 5004.499550044967, 5027.874303142305, 5038.193888977746, 5067.493250674903, 3657.140571965639, 3697.4268988306853, 3787.450819631193, 3799.829007694605, 3814.294355544233, 5407.297051383295, 5409.377921539073, 5413.675179489243, 5440.455954404528, 5449.23710680513, 4958.900821983612, 5182.97408512955, 5249.816256431007, 5257.4742525747115, 5268.1834315681135, 5186.395455158779, 5209.85816276069, 5228.63399562027, 5234.528892399702, 5248.343957005374, 5138.4604616514725, 5162.664426812245, 5164.790695583088, 5180.145276029413, 5207.531322181022, 4974.0, 5225.634205605571, 5249.2651028855225, 5253.219570191108, 5270.341207349082, 5131.281620573048, 5135.229715542699, 5137.537621614073, 5142.508672485087, 5170.48295170495], "value": 3873.598173271528, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.122334847955, 656.7668477690423, 681.505908216543, 698.8846840271352, 712.3410844968405, 12751.489940402384, 13086.083974121815, 13551.79672304915, 13625.386857591408, 13729.31353432328, 13066.673333166651, 13161.868381316184, 13422.664433389145, 13432.32838358083, 13436.193828370304, 797.7088362747585, 818.8444195602846, 819.4059307002435, 819.9180081991783, 820.6184124382161, 713.2012146396017, 717.4009701898921, 783.6316931042401, 807.0597753617029, 809.3201710563126, 744.4379493482413, 748.1657951384194, 749.7300971650202, 750.89487471754, 750.9624518774067, 817.3297895725508, 842.6081871929551, 845.2688424512813, 847.267113946438, 852.0542198160038, 549.1762356465302, 621.9937800621981, 624.3912185619031, 624.9406306400884, 630.9085182648536, 11467.483963221675, 12319.384030798514, 12339.93830030844, 12376.638569757319, 12458.937705311506, 11180.105591552656, 12556.18384804994, 12651.557195498206, 12990.740185196242, 12998.675033124153, 10765.246432749711, 10863.67408977732, 10894.291871028357, 10908.236423450362, 10922.890771092267, 3204.7436205103536, 3244.0, 3249.3501299740146, 3285.2936618626873, 3389.779664321811, 57130.714346428416, 58712.0, 59380.70309648467, 59793.0, 60126.39873601252, 9482.715518534455, 9614.0, 9779.706608801747, 9830.361026533252, 9869.358491698016, 18510.352137675116, 18724.81275187231, 18766.624667506574, 18768.155433005548, 18779.530511737317, 6456.741730330828, 6487.967560162169, 6492.610443373413, 6532.934670653232, 6566.835829104215, 3545.663161999606, 3651.9634803651625, 3672.6510981457254, 3678.834452449646, 3721.292954338667, 2805.8877644893973, 2875.3099256178784, 2889.4943384907556, 2909.636295463067, 2911.505044142501, 489.87263311538544, 497.52237851662085, 498.6484528407464, 498.99501004989486, 507.092304774457, 548.9725513724297, 574.9145297030891, 587.4213899309177, 593.8693487432757, 608.1311585640204, 8430.873536896952, 8468.745937621881, 8517.0, 8716.651333946698, 9142.675582959715, 549.543878580777, 580.5558747558122, 584.9151872978395, 597.0715537339393, 600.342624825815, 3710.2023065040607, 3768.9811550942068, 4033.838646454109, 4190.0, 4233.491980962242, 44986.30629910212, 57196.0, 57502.0, 58441.0, 59117.40882591205, 53597.73201133969, 53791.73104134532, 54663.72668136714, 55479.72260138673, 56020.43979560234, 49713.50286497161, 50933.236001460005, 51514.0, 51973.0, 52160.0, 7500.68737970842, 7809.453338266494, 7885.454031285869, 8014.118446970945, 8094.51432914027, 30423.54364684487, 34683.30633387268, 35097.82451087728, 35553.288934220654, 36873.44689829709, 38298.23403531969, 41932.0, 43058.78470607627, 43516.56483435125, 43897.561024389346, 10270.897291026995, 10412.895871041193, 11150.944245278723, 11179.161562882653, 11313.830292545454, 11861.881381186078, 12065.457054432396, 12162.75674486523, 13979.720405592034, 14473.203973781543, 11570.421478926188, 11584.826227606423, 12212.694682633006, 12681.175723577939, 12755.61733148007, 12009.699757506132, 12736.808947865604, 12922.935385323013, 12937.482500699867, 13356.799648005093, 11991.940040299743, 12683.682907927006, 12712.745745084861, 12743.853053225253, 12836.679083022998, 8550.401471896908, 8962.759310172456, 9180.495072771062, 9245.583948722458, 9313.068693130905, 82.02603058346129, 8429.831403372022, 8489.151084891462, 8729.650813967624, 9490.193333566724], "value": 6121.19580001055, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1662.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 116.0, 115.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 131.0, 129.0, 181.0, 133.0, 146.0, 143.0, 144.0, 163.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 183.0, 181.0, 182.0, 184.0, 183.0, 183.0, 183.0, 182.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 104.0, 77.0, 78.0, 76.0, 76.0, 91.0, 77.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 108.0, 85.0, 89.0, 85.0, 105.0, 85.0, 87.0, 101.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 117.0, 131.0, 131.0, 130.0, 130.0, 130.0, 130.0], "value": 124.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 173.0, 171.0, 172.0, 172.0, 172.0, 172.0, 171.0, 170.0], "value": 171.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 95.0, 97.0, 97.0, 96.0, 96.0, 96.0, 97.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 68.0, 67.0, 68.0, 66.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 89.0, 87.0, 95.0, 88.0, 94.0, 89.0, 88.0, 87.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 148.0, 156.0, 150.0, 150.0, 150.0, 139.0, 147.0, 194.0, 159.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 181.0, 187.0, 183.0, 217.0, 203.0, 203.0, 208.0, 204.0], "value": 195.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 83.0, 76.0, 81.0, 82.0, 77.0, 77.0, 82.0, 76.0], "value": 80.5, "unit": "score"}]}]} 08:02:34 INFO - Return code: 0 08:02:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:02:34 ERROR - # TBPL SUCCESS # 08:02:34 INFO - Running post-action listener: _resource_record_post_action 08:02:34 INFO - [mozharness: 2017-02-17 16:02:34.537299Z] Finished run-tests step (success) 08:02:34 INFO - Running post-run listener: _resource_record_post_run 08:02:34 INFO - Total resource usage - Wall time: 1226s; CPU: 16.0%; Read bytes: 16384; Write bytes: 402632704; Read time: 240; Write time: 232948 08:02:34 INFO - TinderboxPrint: CPU usage
15.7% 08:02:34 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 240 08:02:34 INFO - TinderboxPrint: I/O write bytes / time
402,632,704 / 232,948 08:02:34 INFO - TinderboxPrint: CPU idle
8,248.6 (84.3%) 08:02:34 INFO - TinderboxPrint: CPU softirq
133.0 (1.4%) 08:02:34 INFO - TinderboxPrint: CPU user
1,344.3 (13.7%) 08:02:34 INFO - TinderboxPrint: Swap in / out
0 / 0 08:02:34 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 104808448; Read time: 36; Write time: 89100 08:02:34 INFO - run-tests - Wall time: 1210s; CPU: 16.0%; Read bytes: 8192; Write bytes: 297824256; Read time: 196; Write time: 143848 08:02:35 INFO - Running post-run listener: _upload_blobber_files 08:02:35 INFO - Blob upload gear active. 08:02:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:02:35 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:02:35 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:02:35 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:02:35 INFO - (blobuploader) - INFO - Open directory for files ... 08:02:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:02:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:02:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:36 INFO - (blobuploader) - INFO - Done attempting. 08:02:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:02:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:02:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:37 INFO - (blobuploader) - INFO - Done attempting. 08:02:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:02:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:02:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:40 INFO - (blobuploader) - INFO - Done attempting. 08:02:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:02:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:02:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:02:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:02:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:02:41 INFO - (blobuploader) - INFO - Done attempting. 08:02:41 INFO - (blobuploader) - INFO - Iteration through files over. 08:02:41 INFO - Return code: 0 08:02:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:02:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:02:41 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98e7a3e6d1506d95bfe857e636bf9243bbf7e2ed71dfedb7b3599c6d782a0c57b8bff22e3ff7fb0cbf2bcdbdc09795e3fa37ab0359e09e87f8b91dc7d52270fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/999082cb24ad2b9e5a59e0d95a49f045e77b2abca7d9c67f74a131ad12d4fa57f305f632d6279ada13350619f9ecb0b8cea4f4e83bbbf5fa9385d2c722794d00", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/490b98ece39b01a2de2843e1cbc63e95ed0d30449257df07c1e4dd57a739d833190477cc15d064cd3479a8236449374e25a585cd40a5f1cc5831d1ff1964d8b7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd4de87af1ec1f97ebda0c177c2bff1e0a2294b7d8ff3cd76a475ca53e9ead48faf7650368effbe7ca5c9dd26f1bb70744dd6c687a5185c9fe1fcab90c971505"} 08:02:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:02:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:02:41 INFO - Contents: 08:02:41 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98e7a3e6d1506d95bfe857e636bf9243bbf7e2ed71dfedb7b3599c6d782a0c57b8bff22e3ff7fb0cbf2bcdbdc09795e3fa37ab0359e09e87f8b91dc7d52270fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/999082cb24ad2b9e5a59e0d95a49f045e77b2abca7d9c67f74a131ad12d4fa57f305f632d6279ada13350619f9ecb0b8cea4f4e83bbbf5fa9385d2c722794d00", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/490b98ece39b01a2de2843e1cbc63e95ed0d30449257df07c1e4dd57a739d833190477cc15d064cd3479a8236449374e25a585cd40a5f1cc5831d1ff1964d8b7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd4de87af1ec1f97ebda0c177c2bff1e0a2294b7d8ff3cd76a475ca53e9ead48faf7650368effbe7ca5c9dd26f1bb70744dd6c687a5185c9fe1fcab90c971505"} 08:02:41 INFO - Running post-run listener: copy_logs_to_upload_dir 08:02:41 INFO - Copying logs to upload dir... 08:02:41 INFO - mkdir: /builds/slave/test/build/upload/logs 08:02:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.408308 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-02-17 08:02:41.477847) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 08:02:41.481492) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98e7a3e6d1506d95bfe857e636bf9243bbf7e2ed71dfedb7b3599c6d782a0c57b8bff22e3ff7fb0cbf2bcdbdc09795e3fa37ab0359e09e87f8b91dc7d52270fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/999082cb24ad2b9e5a59e0d95a49f045e77b2abca7d9c67f74a131ad12d4fa57f305f632d6279ada13350619f9ecb0b8cea4f4e83bbbf5fa9385d2c722794d00", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/490b98ece39b01a2de2843e1cbc63e95ed0d30449257df07c1e4dd57a739d833190477cc15d064cd3479a8236449374e25a585cd40a5f1cc5831d1ff1964d8b7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd4de87af1ec1f97ebda0c177c2bff1e0a2294b7d8ff3cd76a475ca53e9ead48faf7650368effbe7ca5c9dd26f1bb70744dd6c687a5185c9fe1fcab90c971505"} build_url:https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011279 build_url: 'https://queue.taskcluster.net/v1/task/P482Qw7lQhC0z1zIaZppAg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/98e7a3e6d1506d95bfe857e636bf9243bbf7e2ed71dfedb7b3599c6d782a0c57b8bff22e3ff7fb0cbf2bcdbdc09795e3fa37ab0359e09e87f8b91dc7d52270fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/999082cb24ad2b9e5a59e0d95a49f045e77b2abca7d9c67f74a131ad12d4fa57f305f632d6279ada13350619f9ecb0b8cea4f4e83bbbf5fa9385d2c722794d00", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/490b98ece39b01a2de2843e1cbc63e95ed0d30449257df07c1e4dd57a739d833190477cc15d064cd3479a8236449374e25a585cd40a5f1cc5831d1ff1964d8b7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dd4de87af1ec1f97ebda0c177c2bff1e0a2294b7d8ff3cd76a475ca53e9ead48faf7650368effbe7ca5c9dd26f1bb70744dd6c687a5185c9fe1fcab90c971505"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 08:02:41.506311) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:02:41.506696) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1487346073.745249-1492193819 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:02:41.592008) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 08:02:41.592348) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 08:02:41.592653) ========= ========= Total master_lag: 0.16 =========