builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-005 starttime: 1487823688.26 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.259735) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.260123) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.260428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.292432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.292691) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 20:21:28-- 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% 42.3M=0s 2017-02-22 20:21:28 (42.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.232848 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.560949) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.561286) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016575 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:28.612704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:28.613025) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --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 c7b015c488cfb2afbcff295a9639acd85df332f8 --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=7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 20:21:28,959 truncating revision to first 12 chars 2017-02-22 20:21:28,959 Setting DEBUG logging. 2017-02-22 20:21:28,959 attempt 1/10 2017-02-22 20:21:28,959 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7b015c488cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 20:21:30,099 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.482975 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 20:21:30.109824) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.110140) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.110548) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.110806) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 20:21:30.120518) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-22 20:21:30.120902) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:21:30 INFO - MultiFileLogger online at 20170222 20:21:30 in /builds/slave/test 20:21:30 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 20:21:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:30 INFO - {'append_to_log': False, 20:21:30 INFO - 'base_work_dir': '/builds/slave/test', 20:21:30 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:30 INFO - 'branch': 'Firefox-Non-PGO', 20:21:30 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:30 INFO - 'config_files': ('talos/linux_config.py',), 20:21:30 INFO - 'default_actions': ('clobber', 20:21:30 INFO - 'read-buildbot-config', 20:21:30 INFO - 'download-and-extract', 20:21:30 INFO - 'populate-webroot', 20:21:30 INFO - 'create-virtualenv', 20:21:30 INFO - 'install', 20:21:30 INFO - 'run-tests'), 20:21:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:30 INFO - 'download_minidump_stackwalk': True, 20:21:30 INFO - 'download_symbols': 'ondemand', 20:21:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:30 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:30 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:30 INFO - 'gecko_profile': False, 20:21:30 INFO - 'gecko_profile_interval': 0, 20:21:30 INFO - 'installer_path': 'installer.exe', 20:21:30 INFO - 'log_level': 'info', 20:21:30 INFO - 'log_name': 'talos', 20:21:30 INFO - 'log_to_console': True, 20:21:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:21:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:21:30 INFO - 'opt_config_files': (), 20:21:30 INFO - 'pip_index': False, 20:21:30 INFO - 'suite': 'dromaeojs-e10s', 20:21:30 INFO - 'system_bits': '32', 20:21:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:30 INFO - 'title': 'talos-linux64-ix-005', 20:21:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:30 INFO - 'use_talos_json': True, 20:21:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:30 INFO - 'work_dir': 'build'} 20:21:30 INFO - [mozharness: 2017-02-23 04:21:30.561763Z] Running clobber step. 20:21:30 INFO - Running pre-action listener: _resource_record_pre_action 20:21:30 INFO - Running main action method: clobber 20:21:30 INFO - rmtree: /builds/slave/test/build 20:21:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:31 INFO - Running post-action listener: _resource_record_post_action 20:21:31 INFO - [mozharness: 2017-02-23 04:21:31.474480Z] Finished clobber step (success) 20:21:31 INFO - [mozharness: 2017-02-23 04:21:31.474579Z] Running read-buildbot-config step. 20:21:31 INFO - Running pre-action listener: _resource_record_pre_action 20:21:31 INFO - Running main action method: read_buildbot_config 20:21:31 INFO - Using buildbot properties: 20:21:31 INFO - { 20:21:31 INFO - "project": "", 20:21:31 INFO - "product": "firefox", 20:21:31 INFO - "who": "kwierso@gmail.com", 20:21:31 INFO - "installer_path": "public/build/target.tar.bz2", 20:21:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:21:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 20:21:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:21:31 INFO - "stage_platform": "linux64", 20:21:31 INFO - "basedir": "/builds/slave/test", 20:21:31 INFO - "buildnumber": 691, 20:21:31 INFO - "platform": "ubuntu64_hw", 20:21:31 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:21:31 INFO - "slavebuilddir": "test", 20:21:31 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 20:21:31 INFO - "branch": "mozilla-central", 20:21:31 INFO - "script_repo_revision": "production", 20:21:31 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 20:21:31 INFO - "slavename": "talos-linux64-ix-005", 20:21:31 INFO - "repo_path": "mozilla-central" 20:21:31 INFO - } 20:21:31 INFO - Finding installer, test and symbols from parent task. 20:21:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 20:21:32 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 20:21:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 20:21:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:21:32 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 20:21:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 20:21:32 INFO - Running post-action listener: _resource_record_post_action 20:21:32 INFO - [mozharness: 2017-02-23 04:21:32.932845Z] Finished read-buildbot-config step (success) 20:21:32 INFO - [mozharness: 2017-02-23 04:21:32.933061Z] Running download-and-extract step. 20:21:32 INFO - Running pre-action listener: _resource_record_pre_action 20:21:32 INFO - Running main action method: download_and_extract 20:21:32 INFO - mkdir: /builds/slave/test/build/tests 20:21:32 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')]} 20:21:32 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 20:21:32 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:21:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:21:33 INFO - Downloaded 896 bytes. 20:21:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:21:33 INFO - Using the following test package requirements: 20:21:33 INFO - {u'common': [u'target.common.tests.zip'], 20:21:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:21:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:21:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:21:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:21:33 INFO - u'mozbase': [u'target.common.tests.zip'], 20:21:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:21:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:21:33 INFO - u'web-platform': [u'target.common.tests.zip', 20:21:33 INFO - u'target.web-platform.tests.zip'], 20:21:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:21:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:21:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:33 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:37 INFO - Expected file size: 57165889 20:21:37 INFO - Obtained file size: 57165889 20:21:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:21:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 20:21:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:21:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 20:21:49 INFO - Expected file size: 57165889 20:21:49 INFO - Obtained file size: 57165889 20:22:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip 20:22:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:22:00 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 20:22:00 INFO - Expected file size: 14404319 20:22:00 INFO - Obtained file size: 14404319 20:22:01 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')]} 20:22:01 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:01 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:22:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:22:03 INFO - Downloaded 61547669 bytes. 20:22:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:03 INFO - mkdir: /builds/slave/test/properties 20:22:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:22:03 INFO - Writing to file /builds/slave/test/properties/build_url 20:22:03 INFO - Contents: 20:22:03 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 20:22:03 INFO - Running post-action listener: _resource_record_post_action 20:22:03 INFO - Running post-action listener: set_extra_try_arguments 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.287374Z] Finished download-and-extract step (success) 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.287469Z] Running populate-webroot step. 20:22:03 INFO - Running pre-action listener: _resource_record_pre_action 20:22:03 INFO - Running main action method: populate_webroot 20:22:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:22:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:22:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:22:03 INFO - '--tppagecycles', 20:22:03 INFO - '7'], 20:22:03 INFO - 'tests': ['tsvgm']}}, 20:22:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:22:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:22:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:22:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:22:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['damp', 'tps']}, 20:22:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'tests': ['damp', 'tps']}, 20:22:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['dromaeo_dom']}, 20:22:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:22:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['basic_compositor_video']}, 20:22:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:22:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['a11yr', 20:22:03 INFO - 'ts_paint', 20:22:03 INFO - 'tpaint', 20:22:03 INFO - 'sessionrestore', 20:22:03 INFO - 'sessionrestore_no_auto_restore', 20:22:03 INFO - 'tabpaint']}, 20:22:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tsvgx', 20:22:03 INFO - 'tsvgr_opacity', 20:22:03 INFO - 'tart', 20:22:03 INFO - 'tscrollx', 20:22:03 INFO - 'cart', 20:22:03 INFO - 'tsvg_static']}, 20:22:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:22:03 INFO - 'tsvgr_opacity', 20:22:03 INFO - 'tart', 20:22:03 INFO - 'tscrollx', 20:22:03 INFO - 'cart', 20:22:03 INFO - 'tsvg_static']}, 20:22:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s'], 20:22:03 INFO - 'tests': ['tp5o']}, 20:22:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:22:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--disable-e10s', 20:22:03 INFO - '--xperf_path', 20:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:03 INFO - 'tests': ['tp5n']}, 20:22:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:22:03 INFO - 'talos_options': ['--xperf_path', 20:22:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:03 INFO - 'tests': ['tp5n']}}, 20:22:03 INFO - 'talos.zip': {'path': '', 20:22:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:22:03 INFO - Running post-action listener: _resource_record_post_action 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.300532Z] Finished populate-webroot step (success) 20:22:03 INFO - [mozharness: 2017-02-23 04:22:03.300621Z] Running create-virtualenv step. 20:22:03 INFO - Running pre-action listener: _resource_record_pre_action 20:22:03 INFO - Running main action method: create_virtualenv 20:22:03 INFO - Creating virtualenv /builds/slave/test/build/venv 20:22:03 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 20:22:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:22:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:22:03 INFO - Using real prefix '/usr' 20:22:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:22:03 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:05 INFO - Installing pip.................done. 20:22:05 INFO - Return code: 0 20:22:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:22:06 INFO - Reading from file tmpfile_stdout 20:22:06 INFO - Output received: 20:22:06 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) 20:22:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:22:06 INFO - Using env: {'DISPLAY': ':0', 20:22:06 INFO - 'HOME': '/home/cltbld', 20:22:06 INFO - 'LANG': 'en_US.UTF-8', 20:22:06 INFO - 'LANGUAGE': 'en_US:en', 20:22:06 INFO - 'LOGNAME': 'cltbld', 20:22:06 INFO - 'MAIL': '/var/mail/cltbld', 20:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:06 INFO - 'MOZ_NO_REMOTE': '1', 20:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:06 INFO - 'NO_EM_RESTART': '1', 20:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:06 INFO - 'PWD': '/builds/slave/test', 20:22:06 INFO - 'SHELL': '/bin/bash', 20:22:06 INFO - 'SHLVL': '1', 20:22:06 INFO - 'TERM': 'linux', 20:22:06 INFO - 'TMOUT': '86400', 20:22:06 INFO - 'USER': 'cltbld', 20:22:06 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:06 INFO - '_': '/tools/buildbot/bin/python'} 20:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:06 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 20:22:06 INFO - Cleaning up... 20:22:06 INFO - Return code: 0 20:22:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:22:06 INFO - Using env: {'DISPLAY': ':0', 20:22:06 INFO - 'HOME': '/home/cltbld', 20:22:06 INFO - 'LANG': 'en_US.UTF-8', 20:22:06 INFO - 'LANGUAGE': 'en_US:en', 20:22:06 INFO - 'LOGNAME': 'cltbld', 20:22:06 INFO - 'MAIL': '/var/mail/cltbld', 20:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:06 INFO - 'MOZ_NO_REMOTE': '1', 20:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:06 INFO - 'NO_EM_RESTART': '1', 20:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:06 INFO - 'PWD': '/builds/slave/test', 20:22:06 INFO - 'SHELL': '/bin/bash', 20:22:06 INFO - 'SHLVL': '1', 20:22:06 INFO - 'TERM': 'linux', 20:22:06 INFO - 'TMOUT': '86400', 20:22:06 INFO - 'USER': 'cltbld', 20:22:06 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:06 INFO - '_': '/tools/buildbot/bin/python'} 20:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:06 INFO - Downloading/unpacking psutil>=3.1.1 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:22:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:08 INFO - Installing collected packages: psutil 20:22:08 INFO - Running setup.py install for psutil 20:22:08 INFO - building 'psutil._psutil_linux' extension 20:22:08 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 20:22:09 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 20:22:09 INFO - building 'psutil._psutil_posix' extension 20:22:09 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 20:22:09 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 20:22:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:22:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:22:09 INFO - Successfully installed psutil 20:22:09 INFO - Cleaning up... 20:22:09 INFO - Return code: 0 20:22:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:22:09 INFO - Using env: {'DISPLAY': ':0', 20:22:09 INFO - 'HOME': '/home/cltbld', 20:22:09 INFO - 'LANG': 'en_US.UTF-8', 20:22:09 INFO - 'LANGUAGE': 'en_US:en', 20:22:09 INFO - 'LOGNAME': 'cltbld', 20:22:09 INFO - 'MAIL': '/var/mail/cltbld', 20:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:09 INFO - 'MOZ_NO_REMOTE': '1', 20:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:09 INFO - 'NO_EM_RESTART': '1', 20:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:09 INFO - 'PWD': '/builds/slave/test', 20:22:09 INFO - 'SHELL': '/bin/bash', 20:22:09 INFO - 'SHLVL': '1', 20:22:09 INFO - 'TERM': 'linux', 20:22:09 INFO - 'TMOUT': '86400', 20:22:09 INFO - 'USER': 'cltbld', 20:22:09 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:09 INFO - '_': '/tools/buildbot/bin/python'} 20:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:22:11 INFO - Installing collected packages: mozsystemmonitor 20:22:11 INFO - Running setup.py install for mozsystemmonitor 20:22:11 INFO - Successfully installed mozsystemmonitor 20:22:11 INFO - Cleaning up... 20:22:11 INFO - Return code: 0 20:22:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:22:11 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')]} 20:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 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')]} 20:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:11 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 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:11 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 20:22:11 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 20:22:11 INFO - Using env: {'DISPLAY': ':0', 20:22:11 INFO - 'HOME': '/home/cltbld', 20:22:11 INFO - 'LANG': 'en_US.UTF-8', 20:22:11 INFO - 'LANGUAGE': 'en_US:en', 20:22:11 INFO - 'LOGNAME': 'cltbld', 20:22:11 INFO - 'MAIL': '/var/mail/cltbld', 20:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:11 INFO - 'MOZ_NO_REMOTE': '1', 20:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:11 INFO - 'NO_EM_RESTART': '1', 20:22:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:11 INFO - 'PWD': '/builds/slave/test', 20:22:11 INFO - 'SHELL': '/bin/bash', 20:22:11 INFO - 'SHLVL': '1', 20:22:11 INFO - 'TERM': 'linux', 20:22:11 INFO - 'TMOUT': '86400', 20:22:11 INFO - 'USER': 'cltbld', 20:22:11 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:11 INFO - '_': '/tools/buildbot/bin/python'} 20:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:12 INFO - Downloading/unpacking jsonschema==2.5.1 20:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:22:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 INFO - Downloading functools32-3.2.3-2.tar.gz 20:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:22:14 INFO - warning: no files found matching '*.txt' 20:22:14 INFO - no previously-included directories found matching 'build' 20:22:14 INFO - no previously-included directories found matching 'dist' 20:22:14 INFO - no previously-included directories found matching '.git*' 20:22:14 INFO - Installing collected packages: jsonschema, functools32 20:22:14 INFO - Running setup.py install for functools32 20:22:14 INFO - warning: no files found matching '*.txt' 20:22:14 INFO - no previously-included directories found matching 'build' 20:22:14 INFO - no previously-included directories found matching 'dist' 20:22:14 INFO - no previously-included directories found matching '.git*' 20:22:14 INFO - Successfully installed jsonschema functools32 20:22:14 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:22:14 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')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:14 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 20:22:14 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 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:22:14 INFO - Cleaning up... 20:22:14 INFO - Return code: 0 20:22:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:22:14 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')]} 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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')]} 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:14 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 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:14 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 20:22:14 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 20:22:14 INFO - Using env: {'DISPLAY': ':0', 20:22:14 INFO - 'HOME': '/home/cltbld', 20:22:14 INFO - 'LANG': 'en_US.UTF-8', 20:22:14 INFO - 'LANGUAGE': 'en_US:en', 20:22:14 INFO - 'LOGNAME': 'cltbld', 20:22:14 INFO - 'MAIL': '/var/mail/cltbld', 20:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:14 INFO - 'MOZ_NO_REMOTE': '1', 20:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:14 INFO - 'NO_EM_RESTART': '1', 20:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:14 INFO - 'PWD': '/builds/slave/test', 20:22:14 INFO - 'SHELL': '/bin/bash', 20:22:14 INFO - 'SHLVL': '1', 20:22:14 INFO - 'TERM': 'linux', 20:22:14 INFO - 'TMOUT': '86400', 20:22:14 INFO - 'USER': 'cltbld', 20:22:14 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:14 INFO - '_': '/tools/buildbot/bin/python'} 20:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:14 INFO - Downloading/unpacking blobuploader==1.2.4 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:22:16 INFO - Downloading blobuploader-1.2.4.tar.gz 20:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:22:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:22:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 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 20:22:17 INFO - Downloading docopt-0.6.1.tar.gz 20:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:22:17 INFO - Installing collected packages: blobuploader, requests, docopt 20:22:17 INFO - Running setup.py install for blobuploader 20:22:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:22:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:22:17 INFO - Running setup.py install for requests 20:22:18 INFO - Running setup.py install for docopt 20:22:18 INFO - Successfully installed blobuploader requests docopt 20:22:18 INFO - Cleaning up... 20:22:18 INFO - Return code: 0 20:22:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22: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')]} 20:22:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22: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')]} 20:22:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:18 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 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:18 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 20:22:18 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 20:22:18 INFO - Using env: {'DISPLAY': ':0', 20:22:18 INFO - 'HOME': '/home/cltbld', 20:22:18 INFO - 'LANG': 'en_US.UTF-8', 20:22:18 INFO - 'LANGUAGE': 'en_US:en', 20:22:18 INFO - 'LOGNAME': 'cltbld', 20:22:18 INFO - 'MAIL': '/var/mail/cltbld', 20:22:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:18 INFO - 'MOZ_NO_REMOTE': '1', 20:22:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:18 INFO - 'NO_EM_RESTART': '1', 20:22:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:18 INFO - 'PWD': '/builds/slave/test', 20:22:18 INFO - 'SHELL': '/bin/bash', 20:22:18 INFO - 'SHLVL': '1', 20:22:18 INFO - 'TERM': 'linux', 20:22:18 INFO - 'TMOUT': '86400', 20:22:18 INFO - 'USER': 'cltbld', 20:22:18 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:18 INFO - '_': '/tools/buildbot/bin/python'} 20:22:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Running setup.py (path:/tmp/pip-VTmXVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 INFO - Running setup.py (path:/tmp/pip-kpa9Eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:18 INFO - Running setup.py (path:/tmp/pip-m4yUWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:18 INFO - Running setup.py (path:/tmp/pip-uiAAqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:18 INFO - Running setup.py (path:/tmp/pip-g84F36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:18 INFO - Running setup.py (path:/tmp/pip-_1t_zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:19 INFO - Running setup.py (path:/tmp/pip-z8MzqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:19 INFO - Running setup.py (path:/tmp/pip-bc10dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:19 INFO - Running setup.py (path:/tmp/pip-FKKUB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:19 INFO - Running setup.py (path:/tmp/pip-9mx4Xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:19 INFO - Running setup.py (path:/tmp/pip-JyvXpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:19 INFO - Running setup.py (path:/tmp/pip-d7_VAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Running setup.py (path:/tmp/pip-1oCgrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Running setup.py (path:/tmp/pip-sNln5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Running setup.py (path:/tmp/pip-83qneO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:19 INFO - Running setup.py (path:/tmp/pip-uv78r0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:19 INFO - Running setup.py (path:/tmp/pip-DmCIue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:22:19 INFO - Running setup.py install for manifestparser 20:22:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:22:19 INFO - Running setup.py install for mozcrash 20:22:20 INFO - Running setup.py install for mozdebug 20:22:20 INFO - Running setup.py install for mozdevice 20:22:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozfile 20:22:20 INFO - Running setup.py install for mozhttpd 20:22:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozinfo 20:22:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozInstall 20:22:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozleak 20:22:20 INFO - Running setup.py install for mozlog 20:22:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for moznetwork 20:22:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:22:20 INFO - Running setup.py install for mozprocess 20:22:21 INFO - Running setup.py install for mozprofile 20:22:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Running setup.py install for mozrunner 20:22:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Running setup.py install for mozscreenshot 20:22:21 INFO - Running setup.py install for moztest 20:22:21 INFO - Running setup.py install for mozversion 20:22:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:22:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:22:21 INFO - Cleaning up... 20:22:21 INFO - Return code: 0 20:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:21 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 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:21 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 20:22:21 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 20:22:21 INFO - Using env: {'DISPLAY': ':0', 20:22:21 INFO - 'HOME': '/home/cltbld', 20:22:21 INFO - 'LANG': 'en_US.UTF-8', 20:22:21 INFO - 'LANGUAGE': 'en_US:en', 20:22:21 INFO - 'LOGNAME': 'cltbld', 20:22:21 INFO - 'MAIL': '/var/mail/cltbld', 20:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:21 INFO - 'MOZ_NO_REMOTE': '1', 20:22:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:21 INFO - 'NO_EM_RESTART': '1', 20:22:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:21 INFO - 'PWD': '/builds/slave/test', 20:22:21 INFO - 'SHELL': '/bin/bash', 20:22:21 INFO - 'SHLVL': '1', 20:22:21 INFO - 'TERM': 'linux', 20:22:21 INFO - 'TMOUT': '86400', 20:22:21 INFO - 'USER': 'cltbld', 20:22:21 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:21 INFO - '_': '/tools/buildbot/bin/python'} 20:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:22:21 INFO - Running setup.py (path:/tmp/pip-gbmpe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:22:21 INFO - Running setup.py (path:/tmp/pip-nIUqc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:22:21 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)) 20:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:22:21 INFO - Running setup.py (path:/tmp/pip-S4PrNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:22:22 INFO - Running setup.py (path:/tmp/pip-74Garc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:22:22 INFO - Running setup.py (path:/tmp/pip-s_Hhnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:22:22 INFO - Running setup.py (path:/tmp/pip-mKcY92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:22:22 INFO - Running setup.py (path:/tmp/pip-uR1dKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:22:22 INFO - Running setup.py (path:/tmp/pip-E4pqpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:22:22 INFO - Running setup.py (path:/tmp/pip-VXP_4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:22:22 INFO - Running setup.py (path:/tmp/pip-OhmNEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:22:22 INFO - Running setup.py (path:/tmp/pip-7Dvtzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:22:22 INFO - Running setup.py (path:/tmp/pip-10e1Ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:22:22 INFO - Running setup.py (path:/tmp/pip-YcQv2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:22:22 INFO - Running setup.py (path:/tmp/pip-_VSVmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:22 INFO - Running setup.py (path:/tmp/pip-oL0efA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:22:22 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)) 20:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:22:22 INFO - Running setup.py (path:/tmp/pip-zo5gFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:22:23 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)) 20:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:22:23 INFO - Running setup.py (path:/tmp/pip-8AlH8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:22:23 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)) 20:22:23 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)) 20:22:23 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)) 20:22:23 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)) 20:22:23 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)) 20:22:23 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)) 20:22:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:22:23 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 20:22:23 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 20:22:23 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 20:22:23 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 20:22:23 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 20:22:23 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 20:22:25 INFO - Downloading blessings-1.6.tar.gz 20:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:22:25 INFO - Installing collected packages: blessings 20:22:25 INFO - Running setup.py install for blessings 20:22:25 INFO - Successfully installed blessings 20:22:25 INFO - Cleaning up... 20:22:25 INFO - Return code: 0 20:22:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:22:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:25 INFO - Reading from file tmpfile_stdout 20:22:25 INFO - Current package versions: 20:22:25 INFO - argparse == 1.2.1 20:22:25 INFO - blessings == 1.6 20:22:25 INFO - blobuploader == 1.2.4 20:22:25 INFO - docopt == 0.6.1 20:22:25 INFO - functools32 == 3.2.3-2 20:22:25 INFO - jsonschema == 2.5.1 20:22:25 INFO - manifestparser == 1.1 20:22:25 INFO - mozInstall == 1.12 20:22:25 INFO - mozcrash == 1.0 20:22:25 INFO - mozdebug == 0.1 20:22:25 INFO - mozdevice == 0.49 20:22:25 INFO - mozfile == 1.2 20:22:25 INFO - mozhttpd == 0.7 20:22:25 INFO - mozinfo == 0.9 20:22:25 INFO - mozleak == 0.1 20:22:25 INFO - mozlog == 3.4 20:22:25 INFO - moznetwork == 0.27 20:22:25 INFO - mozprocess == 0.25 20:22:25 INFO - mozprofile == 0.28 20:22:25 INFO - mozrunner == 6.13 20:22:25 INFO - mozscreenshot == 0.1 20:22:25 INFO - mozsystemmonitor == 0.3 20:22:25 INFO - moztest == 0.8 20:22:25 INFO - mozversion == 1.4 20:22:25 INFO - psutil == 3.1.1 20:22:25 INFO - requests == 1.2.3 20:22:25 INFO - wsgiref == 0.1.2 20:22:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 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 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:25 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 20:22:25 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 20:22:25 INFO - Using env: {'DISPLAY': ':0', 20:22:25 INFO - 'HOME': '/home/cltbld', 20:22:25 INFO - 'LANG': 'en_US.UTF-8', 20:22:25 INFO - 'LANGUAGE': 'en_US:en', 20:22:25 INFO - 'LOGNAME': 'cltbld', 20:22:25 INFO - 'MAIL': '/var/mail/cltbld', 20:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:25 INFO - 'MOZ_NO_REMOTE': '1', 20:22:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:25 INFO - 'NO_EM_RESTART': '1', 20:22:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:25 INFO - 'PWD': '/builds/slave/test', 20:22:25 INFO - 'SHELL': '/bin/bash', 20:22:25 INFO - 'SHLVL': '1', 20:22:25 INFO - 'TERM': 'linux', 20:22:25 INFO - 'TMOUT': '86400', 20:22:25 INFO - 'USER': 'cltbld', 20:22:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:25 INFO - '_': '/tools/buildbot/bin/python'} 20:22:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 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)) 20:22:25 INFO - Cleaning up... 20:22:25 INFO - Return code: 0 20:22:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcd4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe79b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, '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': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', '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 20:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:22:25 INFO - Using env: {'DISPLAY': ':0', 20:22:25 INFO - 'HOME': '/home/cltbld', 20:22:25 INFO - 'LANG': 'en_US.UTF-8', 20:22:25 INFO - 'LANGUAGE': 'en_US:en', 20:22:25 INFO - 'LOGNAME': 'cltbld', 20:22:25 INFO - 'MAIL': '/var/mail/cltbld', 20:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:25 INFO - 'MOZ_NO_REMOTE': '1', 20:22:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:25 INFO - 'NO_EM_RESTART': '1', 20:22:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:25 INFO - 'PWD': '/builds/slave/test', 20:22:25 INFO - 'SHELL': '/bin/bash', 20:22:25 INFO - 'SHLVL': '1', 20:22:25 INFO - 'TERM': 'linux', 20:22:25 INFO - 'TMOUT': '86400', 20:22:25 INFO - 'USER': 'cltbld', 20:22:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:25 INFO - '_': '/tools/buildbot/bin/python'} 20:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:22:26 INFO - Cleaning up... 20:22:26 INFO - Return code: 0 20:22:26 INFO - Running post-action listener: _resource_record_post_action 20:22:26 INFO - Running post-action listener: _start_resource_monitoring 20:22:26 INFO - Starting resource monitoring. 20:22:26 INFO - [mozharness: 2017-02-23 04:22:26.086466Z] Finished create-virtualenv step (success) 20:22:26 INFO - [mozharness: 2017-02-23 04:22:26.086978Z] Running install step. 20:22:26 INFO - Running pre-action listener: _resource_record_pre_action 20:22:26 INFO - Running main action method: install 20:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:22:26 INFO - Reading from file tmpfile_stdout 20:22:26 INFO - Detecting whether we're running mozinstall >=1.0... 20:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:22:26 INFO - Reading from file tmpfile_stdout 20:22:26 INFO - Output received: 20:22:26 INFO - Usage: mozinstall [options] installer 20:22:26 INFO - Options: 20:22:26 INFO - -h, --help show this help message and exit 20:22:26 INFO - -d DEST, --destination=DEST 20:22:26 INFO - Directory to install application into. [default: 20:22:26 INFO - "/builds/slave/test"] 20:22:26 INFO - --app=APP Application being installed. [default: firefox] 20:22:26 INFO - mkdir: /builds/slave/test/build/application 20:22:26 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'] 20:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:22:41 INFO - Reading from file tmpfile_stdout 20:22:41 INFO - Output received: 20:22:41 INFO - /builds/slave/test/build/application/firefox/firefox 20:22:41 INFO - Running post-action listener: _resource_record_post_action 20:22:41 INFO - [mozharness: 2017-02-23 04:22:41.830756Z] Finished install step (success) 20:22:41 INFO - [mozharness: 2017-02-23 04:22:41.831029Z] Running run-tests step. 20:22:41 INFO - Running pre-action listener: _resource_record_pre_action 20:22:41 INFO - Running main action method: run_tests 20:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:22:41 INFO - Python 2.7.3 20:22:41 INFO - Return code: 0 20:22:41 INFO - grabbing minidump binary from tooltool 20:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:22:41 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 0xe77290>, '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 0xe77b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe78040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:22:41 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 20:22:41 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 20:22:41 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 20:22:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:22:41 INFO - Return code: 0 20:22:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:22:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:22:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:22:41 INFO - ENV: RUST_BACKTRACE is now 1 20:22:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:22:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:22:41 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 20:22:41 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 20:22:41 INFO - Using env: {'DISPLAY': ':0', 20:22:41 INFO - 'HOME': '/home/cltbld', 20:22:41 INFO - 'LANG': 'en_US.UTF-8', 20:22:41 INFO - 'LANGUAGE': 'en_US:en', 20:22:41 INFO - 'LOGNAME': 'cltbld', 20:22:41 INFO - 'MAIL': '/var/mail/cltbld', 20:22:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:22:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:41 INFO - 'MOZ_NO_REMOTE': '1', 20:22:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:22:41 INFO - 'NO_EM_RESTART': '1', 20:22:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:41 INFO - 'PWD': '/builds/slave/test', 20:22:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:22:41 INFO - 'RUST_BACKTRACE': '1', 20:22:41 INFO - 'SHELL': '/bin/bash', 20:22:41 INFO - 'SHLVL': '1', 20:22:41 INFO - 'TERM': 'linux', 20:22:41 INFO - 'TMOUT': '86400', 20:22:41 INFO - 'USER': 'cltbld', 20:22:41 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668', 20:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:41 INFO - '_': '/tools/buildbot/bin/python'} 20:22:41 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/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 20:22:42 INFO - mozversion application_buildid: 20170221181700 20:22:42 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:42 INFO - mozversion application_display_name: Nightly 20:22:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:22:42 INFO - mozversion application_name: Firefox 20:22:42 INFO - mozversion application_remotingname: firefox 20:22:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:22:42 INFO - mozversion application_vendor: Mozilla 20:22:42 INFO - mozversion application_version: 54.0a1 20:22:42 INFO - mozversion platform_buildid: 20170221181700 20:22:42 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 20:22:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:22:42 INFO - mozversion platform_version: 54.0a1 20:22:42 INFO - using testdate: 1487823762 20:22:42 INFO - actual date: 1487823762 20:22:42 INFO - starting webserver on 'localhost:34854' 20:22:42 INFO - SUITE-START | Running 2 tests 20:22:42 INFO - TEST-START | dromaeo_css 20:22:42 INFO - operating with platform_type : linux_ 20:22:42 INFO - Initialising browser for dromaeo_css test... 20:22:42 INFO - TEST-INFO | started process 27223 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJgP00F/profile http://localhost:34854/getInfo.html) 20:22:45 INFO - PROCESS | 27223 | Iterating over profile /tmp/tmpJgP00F/profile 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/extensions.ini = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/secmod.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/thumbnails = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/cert8.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/safebrowsing = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/user.js = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/gmp = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/directoryLinks.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/key3.db = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/minidumps = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/places.sqlite = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/.parentlock = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/frequencyCap.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | Stat failure 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/cache2 = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/startupCache = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/blocklist.xml = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/permissions.sqlite = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/pluginreg.dat = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/extensions = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/addons.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/places.sqlite-shm = Wed Feb 22 2017 20:22:43 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/localstore.rdf = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/bookmarkbackups = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | __metrics Screen width/height:1600/1200 20:22:46 INFO - PROCESS | 27223 | colorDepth:24 20:22:46 INFO - PROCESS | 27223 | Browser inner width/height: 1024/697 20:22:46 INFO - PROCESS | 27223 | __metrics 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/crashes = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/extensions.json = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/compatibility.ini = Wed Feb 22 2017 20:22:42 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/content-prefs.sqlite = Wed Feb 22 2017 20:22:44 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/prefs.js = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:22:46 INFO - PROCESS | 27223 | No birth date. Using mtime. 20:22:46 INFO - PROCESS | 27223 | Using date: /tmp/tmpJgP00F/profile/places.sqlite-wal = Wed Feb 22 2017 20:22:46 GMT-0800 (PST) 20:22:47 ERROR - PROCESS | 27223 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:22:48 INFO - TEST-INFO | 27223: exit 0 20:22:48 INFO - Browser initialized. 20:22:48 INFO - Running cycle 1/1 for dromaeo_css test... 20:22:48 INFO - TEST-INFO | started process 27349 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJgP00F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:23:00 INFO - PROCESS | 27349 | RSS: Main: 153468928 20:23:00 INFO - PROCESS | 27349 | 20:26:04 INFO - PROCESS | 27349 | Cycle 1(1): loaded http://localhost:34854/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34854/tests/dromaeo/cssquery-ext.html) 20:26:05 INFO - PROCESS | 27349 | RSS: Main: 163344384 20:26:05 INFO - PROCESS | 27349 | 20:27:48 INFO - PROCESS | 27349 | ************************* 20:27:48 INFO - PROCESS | 27349 | A coding exception was thrown and uncaught in a Task. 20:27:48 INFO - PROCESS | 27349 | 20:27:48 ERROR - PROCESS | 27349 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:27:48 INFO - PROCESS | 27349 | Full stack: 20:27:48 INFO - PROCESS | 27349 | ************************* 20:29:08 INFO - PROCESS | 27349 | Cycle 1(1): loaded http://localhost:34854/tests/dromaeo/cssquery-ext.html (next: http://localhost:34854/tests/dromaeo/cssquery-jquery.html) 20:29:08 INFO - PROCESS | 27349 | RSS: Main: 158101504 20:29:08 INFO - PROCESS | 27349 | 20:32:10 INFO - PROCESS | 27349 | Cycle 1(1): loaded http://localhost:34854/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34854/tests/dromaeo/cssquery-mootools.html) 20:32:11 INFO - PROCESS | 27349 | RSS: Main: 166158336 20:32:11 INFO - PROCESS | 27349 | 20:35:13 INFO - PROCESS | 27349 | Cycle 1(1): loaded http://localhost:34854/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34854/tests/dromaeo/cssquery-prototype.html) 20:35:14 INFO - PROCESS | 27349 | RSS: Main: 158093312 20:35:14 INFO - PROCESS | 27349 | 20:38:16 INFO - PROCESS | 27349 | Cycle 1(1): loaded http://localhost:34854/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34854/tests/dromaeo/cssquery-yui.html) 20:38:17 INFO - PROCESS | 27349 | RSS: Main: 165003264 20:38:17 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | Cycle 1(1): loaded http://localhost:34854/tests/dromaeo/cssquery-yui.html (next: http://localhost:34854/tests/dromaeo/cssquery-dojo.html) 20:41:20 INFO - PROCESS | 27349 | RSS: Main: 159195136 20:41:20 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | __start_tp_report 20:41:20 INFO - PROCESS | 27349 | _x_x_mozilla_page_load 20:41:20 INFO - PROCESS | 27349 | _x_x_mozilla_page_load_details 20:41:20 INFO - PROCESS | 27349 | |i|pagename|runs| 20:41:20 INFO - PROCESS | 27349 | |0;dojo.html;2523.2682522068603;2541.75853293937;2543.923682289531;2556.2714626331476;2564.5383830910428;4362.389265502832;4464.330350447434;4509.098180363928;4563.931541026883;4621.098885717278;4420.22646036943;4501.369808226851;4560.589546940775;4570.223062079446;4599.402077729899;4188.81150348235;4246.808893599796;4253.936190957138;4269.3595960606;4296.205202037633;4285.185814695214;4303.935440968388;4304.053108316181;4322.567743225683;4368.89077773055;4032.233875563663;4037.596240375968;4098.815553300079;4227.957720422787;4257.936130958038;4143.854965076238;4160.83356665733;4181.435506206653;4205.432266643995;4217.40956266123;4132.33882578786;4144.295469770146;4167.333226683746;4184.7070705050655;4192.664586833076;4426.955730442686;4449.087936972913;4468.709533880287;4517.277235642281;4521.344405061281;4820.39745031871;4847.5637192652475;4857.101436234308;4882.267659851015;4934.506549345073;4890.437599676012;4895.657303988723;4897.094037603055;4907.092187945242;4939.8024079036795;4391.429114215131;4590.150822097922;4600.148972440109;4601.102784956968;4636.8145274188655;4802.73584952824;4857.781399837016;4865.489123642037;4866.343043689128;4877.5366340197625;4666.206744853315;4686.718796872198;4746.810127594927;4781.402324709411;4784.808607655655;4873.6832105913;4878.463369029404;4885.071836351153;4927.310176575283;4941.407031156285;4825.662203645711;4865;4880.731559764247;4882.292067650171;4922.064807686528;4663.673542851968;4712.787924543404;4744.572988431058;4781.497942715965;4880.341153944243;4134.73124246923;4139.441175441337;4143.606357396043;4172.415861779354;4172.83308667656;3595.5865075516126;3599.0642432967616;3604.098975256186;3631.1829838286703;3637.563492380931;4066;4107.9178416432105;4193.601607847249;4230.323148296257;4241.830326786894;4826.975865120652;4844.224924012141;4873.902521949542;4899.559039686445;4905.70565766048;4217.725847819882;4223.598758117974;4224.55642157569;4268.807903644343;4284.700070995001;4298.3552467130185;4323.740575565476;4387.605115539617;4409.250427427345;4431.601155895984;4519.367288579667;4524.140413321458;4531.864044078683;4535.8185672574045;4536.183486972377;4799.3760811095735;4829.782659780248;4836.95163048365;4855.053264613366;4856.68431551948;4488.5960263575125;4504.774761261859;4529.275315949432;4541.386912766734;4589.151007063704;4754.619630429626;4760.09558183944;4764.880877978078;4768.57082862544;4814.542618451242;4798;4824.372831531879;4826.300186472871;4835.8065677372515;4866.7079975201605;4578.359029735773;4618.284165954283;4637.049404871926;4645.5586719262965;4659.930101048555;4632.976835115803;4661.743604101672;4665.4634717007975;4702.7883745230865;4735.076660051257;4590.862274131781;4596.287575425815;4639.396878405786;4644.117617652635;4688.765561721969;4603.907921841612;4650.4186976627925;4715.905681886275;4730.834420795256;4732.384789977281;4644.512326205835;4661.207594708975;4673.088747694167;4693.647976401715;4695.647826413101 20:41:20 INFO - PROCESS | 27349 | |1;ext.html;532.8587924200087;539.9487048730371;552.6794459213656;573.1317054662185;592.8221533539937;38493.23013539731;40556.39165412521;40798.59201407985;43370;43462.565374346246;41335.45310015904;41840.56072710551;41886.79056604713;41903.79048104755;41962;7595.544267343949;7744.148143704188;7753.418493612973;7760.7283745068935;7764;9322.300827437935;9575.281853860954;9591.088846559565;9772.511374431286;9826;9528;9627.229821614254;9655.806883862319;9689.467079310634;9695.272854535902;9602.231821454301;9606.039396060409;9628.3260171788;9635.181009614227;9656.082672146205;9453.054694530561;9459.66891158813;9485;9519.190868776162;9540.093691099335;34087.97736067922;34149;34297.48553771695;34362.6563734362;34406;3313.2710803623163;3313.4698448248164;3374.5275661407422;3380.222548813762;3403.1322012886803;24960.251192464257;25437.491250175077;25486.36284092894;25535.48929021428;25559;3390.491426286052;3514.736394770377;3520.5951315598777;3658.140337020798;3701.1117331840433;233638;233840.83079584665;234436;234600;235388;39563.999999999425;40914.18171636551;46375.579907214684;47029.5297047032;47075.5292447078;60084.69957650184;60856.08715869265;61387;61706.69146654238;67193.66403167952;13413.262270575213;13466.663333416744;13521;13629.18224906489;13725.656858578415;9577.042295770507;9596.23230141587;9619.470929098827;9626.518674066408;9634.951825240829;18886.81113188878;19127.713084303745;19160.425187244404;19165.137568809438;19219.32732354361;6969.6515174241085;6978.266627354341;6996.790096297119;7090.865461526131;7302.634868256566;716.8996340512228;727.4326025699962;731.4623751542612;735.797655644702;741.6884908338511;26748.331291717863;27892.30269243285;28141.155765327072;28400.85799570989;28625.57061644077;857.1300130367682;864.8789169516273;869.7043005378201;876.2551830943647;899.3075331994328;14316.642083947985;14566.271686415637;14757.188354640597;15017.24913754308;15043.398264069534;339283;345247;348073;348899;349313;35655.4651680225;35740.28519429597;35795.10512237163;35972.460413093824;36039;1104.276698762312;1154.9076073914066;1158.8956993870424;1159.4608507044265;1170.4908364861312;25263.242102736946;26249.475010500064;26783.54686446408;27003.59494607543;27348.179554613394;42252.15495690008;42647.57352426436;42725.718228453195;42744.14511709811;43247.35128973131;277655;278817;279525;279661;279842;29569.556456653598;30525.847370763004;35741.642583573834;37166.814165929;38205.23589528138;40137.59862401456;40483;40630.39054414245;41649.79175104105;41693.37459938164;35124.297514050086;35282.11794705153;35509.467357990165;35726.821365894044;35778.463323049655;34442.311153777286;34532.65467345295;34546.82726586351;34647.30705385929;35006.47490287699;33459.49810752792;33683.326333473684;33950.66049339475;34108.82945585256;34455.827720861234;28650.853965841132;31399.372012560078;31436.685633143377;31452;31557.52663710092;23561.29316120519;23784.524309514058;23798.286051418487;24017.51964960726;24156.154534591205 20:41:20 INFO - PROCESS | 27349 | |2;jquery.html;389.4217087624878;389.9434581985612;390.4728616367903;397.65006794021264;422.3453646847388;16833.747493787603;17816.287348506077;18398.54003649908;18439.170237339302;18668.53328666784;16983.660326793488;17052.23264953077;17146.914265428688;17282.740758888594;17335.739963900553;5042.067217564744;5072.822451214208;5253.527182553569;5267.55225805807;5284.048871203182;5117.232415137741;5235.450277720842;5238.319018527586;5245.763940622681;5247.580193584504;5162.922556161642;5174.844754657366;5195.636305458619;5201.609879259052;5208.895822083556;5001.974595207992;5088.643794934356;5108.055009823176;5131.307273518065;5135.922961155586;4562.543745625444;4566.88582785433;4567.1550763108935;4569.977150114228;4572.222722137245;13230.536931207362;16149.838501614951;20933.89533052325;21766.673499897515;21905.890470547547;2101.8423618228694;2161.0923412166926;2193.5064610462678;2218.744844342889;2222.411061068818;9726.610935562569;9901.257405694601;10094.141997930185;11953.701157471045;13414.798778018338;1612.0730579916478;1635.803703555581;1650.3976048742243;1664.675388299282;1726.8359505846925;72282.2771772286;75550.62224688896;75563.24436755672;77537.22462775413;78592;10480.947595262127;10928.945355273332;10934;10967.396793176453;11058.612948546763;15539.689206216039;15557.766633500507;15574.299156537983;15578.454754083552;15584.92207538955;6740.730370785212;6985;7013.859722805515;7193.964030179815;7248.275172482709;6262.655553944577;6311.147995020705;6459.128017717548;6476.4495017924;6504.642244676588;4596.4254468191475;4641.489436162019;4654.604358629555;4731.834385796481;4755.9286610700865;1961.3331467301186;1963.7741659709316;1975.1309423853459;1986.2750096214777;2020.3433883987764;730.4521608793405;749.8350362920149;754.6340025087828;760.5322726523149;763.8319569694659;17376.304947801946;17639.559011024827;18018.729719054223;18235.17941692627;18599.628007439776;751.7631945937012;753.9396848252236;764.3846703403814;764.9388048956071;783.7374479549301;9538.427694338361;9988.900110998942;10080.848787268196;10095.7476063099;10155.187584993182;473565;481095;481296;493305;493395;61029.38970610326;64250.67874660597;84081.15918840855;84610.57694711487;85669;925.833349997007;929.2287401456774;966.367029595616;968.3608818179968;969.3263182088381;15070.924645376703;15341.232938353261;15777.842221577637;15932.645725113032;15989.280482378088;38346.233075338896;38704.80647596744;38714.6128538711;39120.608793912834;39412.60587394089;50921.49078509167;51009;51299;51792.741036294574;52325;7498.687729647399;7743.4966727162555;7921.366290696616;8039.236272554098;8449.408541402043;9212.124848139416;11830.231034982698;12263.632091037283;12714.173578717353;12821.102522823312;9228.723138305862;9691.660791872251;9949;10241.436720980417;10310;10129.64546240878;11013.449327533752;11132.443377831238;11198.328100314007;11354.659360219206;9405.388649737744;10296.433696146783;10596.788064238517;10923.12614990783;11035.841236670034;7906.841863162589;7971.883936248813;8015.679372825255;8181.918180818354;8410.747677569683;8242.17578242171;10376.273660843668;10398.74003149897;10486.79026419452;10633.840492392776 20:41:20 INFO - PROCESS | 27349 | |3;mootools.html;1332.9733405331895;1347.6294019144734;1353.546561901763;1354.1942544186218;1361.448613311609;7221.350078492935;7389.556626602408;7547.584882831442;7558.093028836541;7583.6966521339145;7687.807804804883;7776.611169441519;7777.4555781095205;7787.571683557402;7788.532688038722;2036.7454068241432;2075.885826279554;2078.3557097299886;2080.3238947342097;2084.5101401170787;2052.1175894365415;2091.592139532792;2096.0986775686447;2100.9159633614636;2105.0737675422843;2085.937421877346;2097.475631092227;2118.67160590108;2118.7033815265877;2119.4171602809197;2038.8878611676353;2052.917883284667;2062.679884873682;2063.308791554831;2070.6997485364614;1743.3549586653019;1786.1872847854195;2015.7782643909159;2055.4553043443498;2111.345482900306;7224.602646854473;7438.479306448551;7463.477556571042;7578.924210757877;7586.620668966585;4389.1660584488845;4523;4524.3213517972235;4529.365888775574;4534.6825722199455;2798.552231642948;2815.2398852309966;2853.6432945881766;2856.1717102040384;2886.8123571967753;1064.914806815453;1070.6841481762822;1111.1610733895939;1119.1997721628993;1132.5356603792488;10153.898461015444;10239.436830974293;10276.845847312221;10277.537510811957;10303.381797092123;3712.6287371262656;3825.8660946866726;3926;3929.901752456154;3957.4855268815445;8146.796330091676;8226.136255693065;8235.917640823634;8236.299914507214;8251.83496330082;4480.081583275421;4505.16654418927;4509.842155524541;4516.119356725407;4519.05099929012;4147.52303485097;4155.397467367276;4217.683673724483;4241.7879106044575;4266.807993640294;3235.514672799052;3281.786683865541;3303.1907182740383;3313.867445302161;3397.558317418769;994.8109859126743;1001.8497225416246;1010.4796030044533;1015.746063484129;1023.8822535408524;782.4444644302608;812.1269635142246;812.5896422306815;820.5609998650813;821.880827280041;4664.626829853603;4689.742064186435;4844.394450693663;4874.561289483963;5052.1663925451885;489.81876705618436;491.34160225298274;526.3552148617989;528.7409169506914;538.9649672771258;3250.7724459287624;3295.6869097435706;3303.9834800825843;3310.9503357449657;3390.983045084808;7960.925275087905;8261.463004904663;8585.871211931826;8837.160469755492;8897.777555561033;9076.364654474124;9138.177564019137;9154;9157.77105572366;9177.678781242625;6443;6620.106285651472;6745.460363170934;6749.156355455568;6752.189737231563;4938.555530002317;4942.752862356796;4964.106460837084;4997.350344455198;5046.34397528319;7625.275598818103;7762;7860.882086768589;7874.251946065115;7929.246721561442;7987.361011119213;8064.112947575879;8133.186681332056;8156.755297341089;8166.795830104057;5762.049261871744;5888.94111058901;5900.911486327623;5905.025670764448;5906.911396328973;6817.7272909083085;6831.555948863308;6884.621345825827;6896.27589103137;6932.792016239521;6231.1276421300145;6235.127082208585;6260.780872669435;6295.150154729053;6311.116443697978;6170.167027451236;6241.875162496815;6254.874902502015;6299.4645455135;6300.527460440577;5604.523615492566;5696.601237913472;5735.770569177186;5760.279965004374;5813.6221145625395;4448.510663827041;4468.530804265635;4558.498565157764;4635.860924172281;4719.669623126349;4127.525334586561;4541.68208225421;4588.632909367309;4626.907461850811;4748.0978614063215 20:41:20 INFO - PROCESS | 27349 | |4;prototype.html;350.6440962423143;357.77638975640224;365.7366695978895;367.4653379333069;368.96310368963105;4897.559219670229;4925.1627223372025;4968.527989840959;4972.751362431877;5019;4874.269634279824;4953.975230123844;4959.305697202394;4962.801487940478;4971.37857767779;3705.888823335291;3867.994321476422;3872.3223435898744;3892.9221415571674;3909.1009067914397;3897.395903634927;3899.6880249579967;3909.491766070407;3916.138449541096;3917.6278253566006;3860.343741563941;3869.5163104611925;3872.6514613684903;3873.4964454621;3873.922521549567;3669.853205871762;3882.650561449469;3884.7086468514976;3885.358915778893;3902.1415288636595;3391.1861153323025;3394.62659107498;3415.9146021349416;3416.265502916885;3417.863285468603;4909.538775163501;4909.582685471739;4917.467504235322;4919.163742163805;4945;4420.734755914656;4426.15902978433;4426.490953540352;4435.911281774346;4442.378067070613;4958.305837182797;5202.297689811865;5214.113600687892;5230.829205879555;5240.929832716056;3679.356112680297;3705.1292946157632;3713.4589145504583;3717.5910649828497;3725.5343082114737;5241.580673546107;5444.727763611843;5466.508014278734;5469.507744303032;5494.066008778477;3738.6074462181623;3767.868124615625;3774.3206222880144;3775.942736033915;3783.1487915219404;3807.314683357022;3820.2932457495453;3827.3876179811095;3852.325842977468;3870.8838734838;4205.495340559091;4396.450443694538;4420.491643460979;4422.5356337584735;4429.269170586882;5192.92210616841;5227.503387178212;5235.973820130875;5244.868878278074;5262.184361423985;1287.0668765145383;1293.049608537724;1296.6434230586476;1297.4874924404808;1310.9082364234603;820.782492639451;881.713443130985;884.35883984111;898.0929261445987;910.5674804467825;4441.511433742286;4455.732656040648;4473.955260447419;4474.351219073281;4478.39541661878;5271.077561426811;5275.841724748264;5309.946900531023;5309.973450132802;5325.707086110224;4296.720713153698;4453.309736990772;4456.309272062771;4499.77501124949;4543.363929049936;2759.1722483255344;2781.95676621267;2787.874545645451;2796.231036464948;2797.342624483245;4892.608591312686;4902.730349830722;4928.01439712059;4928.669259299969;4929.630277729113;4993.550580447777;5276.287701160371;5321.840344789663;5341.706206158621;5367.624266301399;4873.658843880894;4882.267659851051;4890.967600087968;4909.754512274372;4910;5209.47905209476;5218.452062533379;5292.788288468418;5303.708296043755;5313.335833020872;4867.5132486751045;5103.413107492686;5141.2288156776785;5214.556762675215;5215.687058776485;4798.904021919612;4877.244027175793;4881.8535443936735;4885.755712214446;4913.606911447004;3775.8300876461176;3782.508273924372;3790.9052273692273;3790.962090379061;3806.238752249506;5347.51872331492;5353;5388;5391.2182733504205;5402.108652072364;4925;5164.509371609691;5201.401838788588;5216.165413533817;5223.973880130727;5160.561352285098;5203.713795741271;5223.399309079505;5250.159974404077;5270.420253772006;5158.200478925773;5179.782751053574;5182.948170518397;5204.531592156724;5221.399539053057;4975.950240497548;5249.501297376743;5249.737513124405;5291.629585929102;5294.576433885203;5132.9743351283005;5138.122651315275;5139.254808052737;5142.665726727901;5153.665011774223 20:41:20 INFO - PROCESS | 27349 | |5;yui.html;641.9967900160499;649.8960166373381;714.2393351081098;723.4863247094563;737.9003834482346;11779.941100294498;12869;12890.935545322283;12903.612891613244;12905.096643234978;11827;11901.166918315721;11917.702057448547;11918.940405297983;11924.642260732171;769.6575024114271;788.597815114293;795.2286282306154;800.4436916343144;803.8472690188875;811.8295158016823;816.9468984516017;818.2349503214484;818.5907046476761;818.8362327534487;849.6771226933787;851.1403482482706;851.974440766778;853.9530325832077;854.4189950833431;842.7640260727006;845.6321500147425;847.5338563789921;848.1052489623445;850.1880703927734;618.7339444039061;620.3982137326785;622.2377587455376;622.3745136138198;623.3703959001396;12235;12690.11169218155;12775.680607984781;12849.421776020008;12948.870511294861;12269.300753834143;12564.685882853002;12627;12671.239725616493;12827.807582886264;10207.234457415649;10209.693709188661;10229.283950123494;10256.128229100535;10765.30025548337;3118.313970926415;3126.265327648001;3251.8536665850093;3281.196106953788;3287.7534184935985;62060;62204.68897655528;62794.37205627931;63731.681341592994;65060.3493965059;8824.691135810215;8983.416077954913;9167.22078623311;9182.540872956326;9184.954075229582;18429.5392615183;18592.81407185911;18690.81309186918;18713.90643046776;18826.529336766416;5964.284285885635;6007.969960150172;6091.269047714302;6240.968795155995;6371.362863713684;3268.150280926976;3341.4152523308326;3350;3427.1603457153155;3462.774919630216;2804.775617950559;2918.7956843020784;2958.7485063769823;2972.806767560145;2980.7466365359187;578.3233616668508;580.9419058094242;586.8415527807468;595.7259660556193;597.9521638268928;572.4390097704273;586.5835256967515;589.5548860610212;599.4814485470037;609.4697613076652;8057.6374063167295;8086.191380861985;8159.306458951056;8436.36727245459;9029.322800789967;609.7987664070846;630.4357599948113;632.7152781248457;641.3746597067822;652.4095693397483;3835.4438606401914;4498.977505112453;4704.15325241453;4706.599939005155;4760.64295177863;63722.6813865937;66144.66927665331;66643.66954130733;69351.3064869355;70138.64930675416;56063.43936560664;56549;57369.42630573641;60109;61686;50884.745576272624;51110.74444627753;51260.487395126314;51470.22794658083;51842.22236666453;8703.434276772243;9276.72169834906;9657.565409556446;9665;9672.758181045248;31627;32637.510437343935;32956.1760955978;33957.49063763996;33960.66039339575;43027.139457211306;44934.775326124465;45175.77412112919;45565;46208.30687539757;10261.486925653837;10296.485175741333;10307.536160872629;10384;10389.896101039196;12243.510259589517;12621.179623324455;12809.334786478072;12835.807462888251;13402.664933376744;10972.286801357886;11350.602728904449;11359.148063895405;11454.713632159262;11514.251573647685;10963.671089867317;11388.829167562659;11407.543698251977;11476.196666233283;11492.885071149181;10605.522751476356;11190.216684832309;11237.887621123684;11284.743884726771;11311.377874216998;8779.912200878165;8880.424377791918;9093.272538197061;9223.677171298972;9383.249340052917;8471.057884231537;8941.418807777734;9023.401556787996;9448.149666530046;9601.183899368632 20:41:20 INFO - PROCESS | 27349 | __end_tp_report 20:41:20 INFO - PROCESS | 27349 | __start_cc_report 20:41:20 INFO - PROCESS | 27349 | _x_x_mozilla_cycle_collect,395 20:41:20 INFO - PROCESS | 27349 | __end_cc_report 20:41:20 INFO - PROCESS | 27349 | __startTimestamp1487824880649__endTimestamp 20:41:20 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | ------- Summary: start ------- 20:41:20 INFO - PROCESS | 27349 | Number of tests: 6 20:41:20 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | [#0] dojo.html Cycles:165 Average:4478.08 Median:4596.29 stddev:456.34 (9.9%) stddev-sans-first:431.20 20:41:20 INFO - PROCESS | 27349 | Values: 2523.3 2541.8 2543.9 2556.3 2564.5 4362.4 4464.3 4509.1 4563.9 4621.1 4420.2 4501.4 4560.6 4570.2 4599.4 4188.8 4246.8 4253.9 4269.4 4296.2 4285.2 4303.9 4304.1 4322.6 4368.9 4032.2 4037.6 4098.8 4228.0 4257.9 4143.9 4160.8 4181.4 4205.4 4217.4 4132.3 4144.3 4167.3 4184.7 4192.7 4427.0 4449.1 4468.7 4517.3 4521.3 4820.4 4847.6 4857.1 4882.3 4934.5 4890.4 4895.7 4897.1 4907.1 4939.8 4391.4 4590.2 4600.1 4601.1 4636.8 4802.7 4857.8 4865.5 4866.3 4877.5 4666.2 4686.7 4746.8 4781.4 4784.8 4873.7 4878.5 4885.1 4927.3 4941.4 4825.7 4865.0 4880.7 4882.3 4922.1 4663.7 4712.8 4744.6 4781.5 4880.3 4134.7 4139.4 4143.6 4172.4 4172.8 3595.6 3599.1 3604.1 3631.2 3637.6 4066.0 4107.9 4193.6 4230.3 4241.8 4827.0 4844.2 4873.9 4899.6 4905.7 4217.7 4223.6 4224.6 4268.8 4284.7 4298.4 4323.7 4387.6 4409.3 4431.6 4519.4 4524.1 4531.9 4535.8 4536.2 4799.4 4829.8 4837.0 4855.1 4856.7 4488.6 4504.8 4529.3 4541.4 4589.2 4754.6 4760.1 4764.9 4768.6 4814.5 4798.0 4824.4 4826.3 4835.8 4866.7 4578.4 4618.3 4637.0 4645.6 4659.9 4633.0 4661.7 4665.5 4702.8 4735.1 4590.9 4596.3 4639.4 4644.1 4688.8 4603.9 4650.4 4715.9 4730.8 4732.4 4644.5 4661.2 4673.1 4693.6 4695.6 20:41:20 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | [#1] ext.html Cycles:180 Average:44341.49 Median:35740.96 stddev:76034.72 (212.7%) stddev-sans-first:76176.87 20:41:20 INFO - PROCESS | 27349 | Values: 532.9 539.9 552.7 573.1 592.8 38493.2 40556.4 40798.6 43370.0 43462.6 41335.5 41840.6 41886.8 41903.8 41962.0 7595.5 7744.1 7753.4 7760.7 7764.0 9322.3 9575.3 9591.1 9772.5 9826.0 9528.0 9627.2 9655.8 9689.5 9695.3 9602.2 9606.0 9628.3 9635.2 9656.1 9453.1 9459.7 9485.0 9519.2 9540.1 34088.0 34149.0 34297.5 34362.7 34406.0 3313.3 3313.5 3374.5 3380.2 3403.1 24960.3 25437.5 25486.4 25535.5 25559.0 3390.5 3514.7 3520.6 3658.1 3701.1 233638.0 233840.8 234436.0 234600.0 235388.0 39564.0 40914.2 46375.6 47029.5 47075.5 60084.7 60856.1 61387.0 61706.7 67193.7 13413.3 13466.7 13521.0 13629.2 13725.7 9577.0 9596.2 9619.5 9626.5 9635.0 18886.8 19127.7 19160.4 19165.1 19219.3 6969.7 6978.3 6996.8 7090.9 7302.6 716.9 727.4 731.5 735.8 741.7 26748.3 27892.3 28141.2 28400.9 28625.6 857.1 864.9 869.7 876.3 899.3 14316.6 14566.3 14757.2 15017.2 15043.4 339283.0 345247.0 348073.0 348899.0 349313.0 35655.5 35740.3 35795.1 35972.5 36039.0 1104.3 1154.9 1158.9 1159.5 1170.5 25263.2 26249.5 26783.5 27003.6 27348.2 42252.2 42647.6 42725.7 42744.1 43247.4 277655.0 278817.0 279525.0 279661.0 279842.0 29569.6 30525.8 35741.6 37166.8 38205.2 40137.6 40483.0 40630.4 41649.8 41693.4 35124.3 35282.1 35509.5 35726.8 35778.5 34442.3 34532.7 34546.8 34647.3 35006.5 33459.5 33683.3 33950.7 34108.8 34455.8 28650.9 31399.4 31436.7 31452.0 31557.5 23561.3 23784.5 23798.3 24017.5 24156.2 20:41:20 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | [#2] jquery.html Cycles:180 Average:27302.36 Median:4618.96 stddev:79610.35 (1723.6%) stddev-sans-first:79808.03 20:41:20 INFO - PROCESS | 27349 | Values: 389.4 389.9 390.5 397.7 422.3 16833.7 17816.3 18398.5 18439.2 18668.5 16983.7 17052.2 17146.9 17282.7 17335.7 5042.1 5072.8 5253.5 5267.6 5284.0 5117.2 5235.5 5238.3 5245.8 5247.6 5162.9 5174.8 5195.6 5201.6 5208.9 5002.0 5088.6 5108.1 5131.3 5135.9 4562.5 4566.9 4567.2 4570.0 4572.2 13230.5 16149.8 20933.9 21766.7 21905.9 2101.8 2161.1 2193.5 2218.7 2222.4 9726.6 9901.3 10094.1 11953.7 13414.8 1612.1 1635.8 1650.4 1664.7 1726.8 72282.3 75550.6 75563.2 77537.2 78592.0 10480.9 10928.9 10934.0 10967.4 11058.6 15539.7 15557.8 15574.3 15578.5 15584.9 6740.7 6985.0 7013.9 7194.0 7248.3 6262.7 6311.1 6459.1 6476.4 6504.6 4596.4 4641.5 4654.6 4731.8 4755.9 1961.3 1963.8 1975.1 1986.3 2020.3 730.5 749.8 754.6 760.5 763.8 17376.3 17639.6 18018.7 18235.2 18599.6 751.8 753.9 764.4 764.9 783.7 9538.4 9988.9 10080.8 10095.7 10155.2 473565.0 481095.0 481296.0 493305.0 493395.0 61029.4 64250.7 84081.2 84610.6 85669.0 925.8 929.2 966.4 968.4 969.3 15070.9 15341.2 15777.8 15932.6 15989.3 38346.2 38704.8 38714.6 39120.6 39412.6 50921.5 51009.0 51299.0 51792.7 52325.0 7498.7 7743.5 7921.4 8039.2 8449.4 9212.1 11830.2 12263.6 12714.2 12821.1 9228.7 9691.7 9949.0 10241.4 10310.0 10129.6 11013.4 11132.4 11198.3 11354.7 9405.4 10296.4 10596.8 10923.1 11035.8 7906.8 7971.9 8015.7 8181.9 8410.7 8242.2 10376.3 10398.7 10486.8 10633.8 20:41:20 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | [#3] mootools.html Cycles:180 Average:4801.45 Median:4796.25 stddev:2658.67 (55.4%) stddev-sans-first:2653.35 20:41:20 INFO - PROCESS | 27349 | Values: 1333.0 1347.6 1353.5 1354.2 1361.4 7221.4 7389.6 7547.6 7558.1 7583.7 7687.8 7776.6 7777.5 7787.6 7788.5 2036.7 2075.9 2078.4 2080.3 2084.5 2052.1 2091.6 2096.1 2100.9 2105.1 2085.9 2097.5 2118.7 2118.7 2119.4 2038.9 2052.9 2062.7 2063.3 2070.7 1743.4 1786.2 2015.8 2055.5 2111.3 7224.6 7438.5 7463.5 7578.9 7586.6 4389.2 4523.0 4524.3 4529.4 4534.7 2798.6 2815.2 2853.6 2856.2 2886.8 1064.9 1070.7 1111.2 1119.2 1132.5 10153.9 10239.4 10276.8 10277.5 10303.4 3712.6 3825.9 3926.0 3929.9 3957.5 8146.8 8226.1 8235.9 8236.3 8251.8 4480.1 4505.2 4509.8 4516.1 4519.1 4147.5 4155.4 4217.7 4241.8 4266.8 3235.5 3281.8 3303.2 3313.9 3397.6 994.8 1001.8 1010.5 1015.7 1023.9 782.4 812.1 812.6 820.6 821.9 4664.6 4689.7 4844.4 4874.6 5052.2 489.8 491.3 526.4 528.7 539.0 3250.8 3295.7 3304.0 3311.0 3391.0 7960.9 8261.5 8585.9 8837.2 8897.8 9076.4 9138.2 9154.0 9157.8 9177.7 6443.0 6620.1 6745.5 6749.2 6752.2 4938.6 4942.8 4964.1 4997.4 5046.3 7625.3 7762.0 7860.9 7874.3 7929.2 7987.4 8064.1 8133.2 8156.8 8166.8 5762.0 5888.9 5900.9 5905.0 5906.9 6817.7 6831.6 6884.6 6896.3 6932.8 6231.1 6235.1 6260.8 6295.2 6311.1 6170.2 6241.9 6254.9 6299.5 6300.5 5604.5 5696.6 5735.8 5760.3 5813.6 4448.5 4468.5 4558.5 4635.9 4719.7 4127.5 4541.7 4588.6 4626.9 4748.1 20:41:20 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | [#4] prototype.html Cycles:180 Average:4293.70 Median:4900.14 stddev:1240.76 (25.3%) stddev-sans-first:1208.43 20:41:20 INFO - PROCESS | 27349 | Values: 350.6 357.8 365.7 367.5 369.0 4897.6 4925.2 4968.5 4972.8 5019.0 4874.3 4954.0 4959.3 4962.8 4971.4 3705.9 3868.0 3872.3 3892.9 3909.1 3897.4 3899.7 3909.5 3916.1 3917.6 3860.3 3869.5 3872.7 3873.5 3873.9 3669.9 3882.7 3884.7 3885.4 3902.1 3391.2 3394.6 3415.9 3416.3 3417.9 4909.5 4909.6 4917.5 4919.2 4945.0 4420.7 4426.2 4426.5 4435.9 4442.4 4958.3 5202.3 5214.1 5230.8 5240.9 3679.4 3705.1 3713.5 3717.6 3725.5 5241.6 5444.7 5466.5 5469.5 5494.1 3738.6 3767.9 3774.3 3775.9 3783.1 3807.3 3820.3 3827.4 3852.3 3870.9 4205.5 4396.5 4420.5 4422.5 4429.3 5192.9 5227.5 5236.0 5244.9 5262.2 1287.1 1293.0 1296.6 1297.5 1310.9 820.8 881.7 884.4 898.1 910.6 4441.5 4455.7 4474.0 4474.4 4478.4 5271.1 5275.8 5309.9 5310.0 5325.7 4296.7 4453.3 4456.3 4499.8 4543.4 2759.2 2782.0 2787.9 2796.2 2797.3 4892.6 4902.7 4928.0 4928.7 4929.6 4993.6 5276.3 5321.8 5341.7 5367.6 4873.7 4882.3 4891.0 4909.8 4910.0 5209.5 5218.5 5292.8 5303.7 5313.3 4867.5 5103.4 5141.2 5214.6 5215.7 4798.9 4877.2 4881.9 4885.8 4913.6 3775.8 3782.5 3790.9 3791.0 3806.2 5347.5 5353.0 5388.0 5391.2 5402.1 4925.0 5164.5 5201.4 5216.2 5224.0 5160.6 5203.7 5223.4 5250.2 5270.4 5158.2 5179.8 5182.9 5204.5 5221.4 4976.0 5249.5 5249.7 5291.6 5294.6 5133.0 5138.1 5139.3 5142.7 5153.7 20:41:20 INFO - PROCESS | 27349 | 20:41:20 INFO - PROCESS | 27349 | [#5] yui.html Cycles:180 Average:14593.72 Median:56306.22 stddev:18484.64 (32.8%) stddev-sans-first:18506.81 20:41:20 INFO - PROCESS | 27349 | Values: 642.0 649.9 714.2 723.5 737.9 11779.9 12869.0 12890.9 12903.6 12905.1 11827.0 11901.2 11917.7 11918.9 11924.6 769.7 788.6 795.2 800.4 803.8 811.8 816.9 818.2 818.6 818.8 849.7 851.1 852.0 854.0 854.4 842.8 845.6 847.5 848.1 850.2 618.7 620.4 622.2 622.4 623.4 12235.0 12690.1 12775.7 12849.4 12948.9 12269.3 12564.7 12627.0 12671.2 12827.8 10207.2 10209.7 10229.3 10256.1 10765.3 3118.3 3126.3 3251.9 3281.2 3287.8 62060.0 62204.7 62794.4 63731.7 65060.3 8824.7 8983.4 9167.2 9182.5 9185.0 18429.5 18592.8 18690.8 18713.9 18826.5 5964.3 6008.0 6091.3 6241.0 6371.4 3268.2 3341.4 3350.0 3427.2 3462.8 2804.8 2918.8 2958.7 2972.8 2980.7 578.3 580.9 586.8 595.7 598.0 572.4 586.6 589.6 599.5 609.5 8057.6 8086.2 8159.3 8436.4 9029.3 609.8 630.4 632.7 641.4 652.4 3835.4 4499.0 4704.2 4706.6 4760.6 63722.7 66144.7 66643.7 69351.3 70138.6 56063.4 56549.0 57369.4 60109.0 61686.0 50884.7 51110.7 51260.5 51470.2 51842.2 8703.4 9276.7 9657.6 9665.0 9672.8 31627.0 32637.5 32956.2 33957.5 33960.7 43027.1 44934.8 45175.8 45565.0 46208.3 10261.5 10296.5 10307.5 10384.0 10389.9 12243.5 12621.2 12809.3 12835.8 13402.7 10972.3 11350.6 11359.1 11454.7 11514.3 10963.7 11388.8 11407.5 11476.2 11492.9 10605.5 11190.2 11237.9 11284.7 11311.4 8779.9 8880.4 9093.3 9223.7 9383.2 8471.1 8941.4 9023.4 9448.1 9601.2 20:41:20 INFO - PROCESS | 27349 | -------- Summary: end -------- 20:41:20 INFO - PROCESS | 27349 | 20:41:21 INFO - TEST-INFO | 27349: exit 0 20:41:21 INFO - TEST-OK | dromaeo_css | took 1119408ms 20:41:21 INFO - TEST-START | kraken 20:41:21 INFO - operating with platform_type : linux_ 20:41:21 INFO - Initialising browser for kraken test... 20:41:21 INFO - TEST-INFO | started process 27723 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwAcn01/profile http://localhost:34854/getInfo.html) 20:41:24 INFO - PROCESS | 27723 | Iterating over profile /tmp/tmpwAcn01/profile 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/extensions.ini = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/secmod.db = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/thumbnails = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/cert8.db = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/safebrowsing = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/user.js = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/gmp = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/directoryLinks.json = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/key3.db = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/sessionCheckpoints.json = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/minidumps = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/places.sqlite = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/.parentlock = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | __metrics Screen width/height:1600/1200 20:41:25 INFO - PROCESS | 27723 | colorDepth:24 20:41:25 INFO - PROCESS | 27723 | Browser inner width/height: 1024/697 20:41:25 INFO - PROCESS | 27723 | __metrics 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/frequencyCap.json = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | Stat failure 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/cache2 = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/startupCache = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/blocklist.xml = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/permissions.sqlite = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/pluginreg.dat = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/extensions = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/addons.json = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/places.sqlite-shm = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/localstore.rdf = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/bookmarkbackups = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/crashes = Wed Feb 22 2017 20:41:21 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/extensions.json = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/compatibility.ini = Wed Feb 22 2017 20:41:22 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/content-prefs.sqlite = Wed Feb 22 2017 20:41:23 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/prefs.js = Wed Feb 22 2017 20:22:00 GMT-0800 (PST) 20:41:25 INFO - PROCESS | 27723 | No birth date. Using mtime. 20:41:25 INFO - PROCESS | 27723 | Using date: /tmp/tmpwAcn01/profile/places.sqlite-wal = Wed Feb 22 2017 20:41:25 GMT-0800 (PST) 20:41:26 ERROR - PROCESS | 27723 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 20:41:27 INFO - TEST-INFO | 27723: exit 0 20:41:27 INFO - Browser initialized. 20:41:27 INFO - Running cycle 1/1 for kraken test... 20:41:27 INFO - TEST-INFO | started process 27851 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwAcn01/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:41:39 INFO - PROCESS | 27851 | RSS: Main: 153743360 20:41:39 INFO - PROCESS | 27851 | 20:41:42 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34854/tests/kraken/driver.html?testName=audio-beat-detection) 20:41:43 INFO - PROCESS | 27851 | RSS: Main: 160083968 20:41:43 INFO - PROCESS | 27851 | 20:41:46 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34854/tests/kraken/driver.html?testName=audio-dft) 20:41:46 INFO - PROCESS | 27851 | RSS: Main: 158834688 20:41:46 INFO - PROCESS | 27851 | 20:41:49 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34854/tests/kraken/driver.html?testName=audio-fft) 20:41:50 INFO - PROCESS | 27851 | RSS: Main: 155234304 20:41:50 INFO - PROCESS | 27851 | 20:41:52 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34854/tests/kraken/driver.html?testName=audio-oscillator) 20:41:53 INFO - PROCESS | 27851 | RSS: Main: 155791360 20:41:53 INFO - PROCESS | 27851 | 20:41:55 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34854/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:41:55 INFO - PROCESS | 27851 | RSS: Main: 156155904 20:41:55 INFO - PROCESS | 27851 | 20:41:58 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34854/tests/kraken/driver.html?testName=imaging-darkroom) 20:41:59 INFO - PROCESS | 27851 | RSS: Main: 161308672 20:41:59 INFO - PROCESS | 27851 | 20:42:03 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34854/tests/kraken/driver.html?testName=imaging-desaturate) 20:42:03 INFO - PROCESS | 27851 | RSS: Main: 157753344 20:42:03 INFO - PROCESS | 27851 | 20:42:06 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34854/tests/kraken/driver.html?testName=json-parse-financial) 20:42:07 INFO - PROCESS | 27851 | RSS: Main: 158941184 20:42:07 INFO - PROCESS | 27851 | 20:42:09 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34854/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:42:09 INFO - PROCESS | 27851 | RSS: Main: 159182848 20:42:09 INFO - PROCESS | 27851 | 20:42:11 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34854/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:42:12 INFO - PROCESS | 27851 | RSS: Main: 159379456 20:42:12 INFO - PROCESS | 27851 | 20:42:14 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34854/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:42:15 INFO - PROCESS | 27851 | RSS: Main: 159764480 20:42:15 INFO - PROCESS | 27851 | 20:42:18 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:42:18 INFO - PROCESS | 27851 | RSS: Main: 160149504 20:42:18 INFO - PROCESS | 27851 | 20:42:21 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:42:22 INFO - PROCESS | 27851 | RSS: Main: 162885632 20:42:22 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:34854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34854/tests/kraken/driver.html?testName=ai-astar) 20:42:24 INFO - PROCESS | 27851 | RSS: Main: 164630528 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | __start_tp_report 20:42:24 INFO - PROCESS | 27851 | _x_x_mozilla_page_load 20:42:24 INFO - PROCESS | 27851 | _x_x_mozilla_page_load_details 20:42:24 INFO - PROCESS | 27851 | |i|pagename|runs| 20:42:24 INFO - PROCESS | 27851 | |0;ai-astar;107;116;115;115;115;115;116;114;116;116 20:42:24 INFO - PROCESS | 27851 | |1;audio-beat-detection;153;127;127;182;129;132;129;135;151;129 20:42:24 INFO - PROCESS | 27851 | |2;audio-dft;163;163;169;166;173;163;163;170;164;165 20:42:24 INFO - PROCESS | 27851 | |3;audio-fft;76;79;77;113;78;78;78;78;99;77 20:42:24 INFO - PROCESS | 27851 | |4;audio-oscillator;85;85;103;86;88;90;95;88;86;108 20:42:24 INFO - PROCESS | 27851 | |5;imaging-gaussian-blur;114;115;116;114;114;119;114;115;115;115 20:42:24 INFO - PROCESS | 27851 | |6;imaging-darkroom;151;152;151;152;150;152;152;154;150;150 20:42:24 INFO - PROCESS | 27851 | |7;imaging-desaturate;96;96;96;97;96;96;97;98;96;96 20:42:24 INFO - PROCESS | 27851 | |8;json-parse-financial;65;65;64;66;66;67;67;76;67;66 20:42:24 INFO - PROCESS | 27851 | |9;json-stringify-tinderbox;65;65;65;65;67;65;65;66;65;66 20:42:24 INFO - PROCESS | 27851 | |10;stanford-crypto-aes;89;88;89;90;92;88;88;88;87;87 20:42:24 INFO - PROCESS | 27851 | |11;stanford-crypto-ccm;142;139;145;154;143;151;144;141;185;171 20:42:24 INFO - PROCESS | 27851 | |12;stanford-crypto-pbkdf2;189;196;181;187;187;187;183;190;182;195 20:42:24 INFO - PROCESS | 27851 | |13;stanford-crypto-sha256-iterative;72;71;76;74;72;70;70;76;70;70 20:42:24 INFO - PROCESS | 27851 | __end_tp_report 20:42:24 INFO - PROCESS | 27851 | __start_cc_report 20:42:24 INFO - PROCESS | 27851 | _x_x_mozilla_cycle_collect,885 20:42:24 INFO - PROCESS | 27851 | __end_cc_report 20:42:24 INFO - PROCESS | 27851 | __startTimestamp1487824944661__endTimestamp 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | ------- Summary: start ------- 20:42:24 INFO - PROCESS | 27851 | Number of tests: 14 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#0] ai-astar Cycles:10 Average:114.50 Median:115.50 stddev:2.72 (2.4%) stddev-sans-first:0.71 20:42:24 INFO - PROCESS | 27851 | Values: 107.0 116.0 115.0 115.0 115.0 115.0 116.0 114.0 116.0 116.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:133.50 stddev:17.77 (13.3%) stddev-sans-first:18.15 20:42:24 INFO - PROCESS | 27851 | Values: 153.0 127.0 127.0 182.0 129.0 132.0 129.0 135.0 151.0 129.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#2] audio-dft Cycles:10 Average:165.90 Median:165.50 stddev:3.57 (2.2%) stddev-sans-first:3.63 20:42:24 INFO - PROCESS | 27851 | Values: 163.0 163.0 169.0 166.0 173.0 163.0 163.0 170.0 164.0 165.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#3] audio-fft Cycles:10 Average:83.30 Median:78.00 stddev:12.44 (15.9%) stddev-sans-first:12.91 20:42:24 INFO - PROCESS | 27851 | Values: 76.0 79.0 77.0 113.0 78.0 78.0 78.0 78.0 99.0 77.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:87.00 stddev:8.09 (9.3%) stddev-sans-first:8.24 20:42:24 INFO - PROCESS | 27851 | Values: 85.0 85.0 103.0 86.0 88.0 90.0 95.0 88.0 86.0 108.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:115.00 stddev:1.52 (1.3%) stddev-sans-first:1.56 20:42:24 INFO - PROCESS | 27851 | Values: 114.0 115.0 116.0 114.0 114.0 119.0 114.0 115.0 115.0 115.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:152.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 20:42:24 INFO - PROCESS | 27851 | Values: 151.0 152.0 151.0 152.0 150.0 152.0 152.0 154.0 150.0 150.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 20:42:24 INFO - PROCESS | 27851 | Values: 96.0 96.0 96.0 97.0 96.0 96.0 97.0 98.0 96.0 96.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:66.50 stddev:3.35 (5.0%) stddev-sans-first:3.48 20:42:24 INFO - PROCESS | 27851 | Values: 65.0 65.0 64.0 66.0 66.0 67.0 67.0 76.0 67.0 66.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 20:42:24 INFO - PROCESS | 27851 | Values: 65.0 65.0 65.0 65.0 67.0 65.0 65.0 66.0 65.0 66.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:88.50 stddev:1.51 (1.7%) stddev-sans-first:1.59 20:42:24 INFO - PROCESS | 27851 | Values: 89.0 88.0 89.0 90.0 92.0 88.0 88.0 88.0 87.0 87.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:148.00 stddev:15.04 (10.2%) stddev-sans-first:15.56 20:42:24 INFO - PROCESS | 27851 | Values: 142.0 139.0 145.0 154.0 143.0 151.0 144.0 141.0 185.0 171.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.70 Median:188.00 stddev:5.06 (2.7%) stddev-sans-first:5.34 20:42:24 INFO - PROCESS | 27851 | Values: 189.0 196.0 181.0 187.0 187.0 187.0 183.0 190.0 182.0 195.0 20:42:24 INFO - PROCESS | 27851 | 20:42:24 INFO - PROCESS | 27851 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:72.00 stddev:2.42 (3.4%) stddev-sans-first:2.57 20:42:24 INFO - PROCESS | 27851 | Values: 72.0 71.0 76.0 74.0 72.0 70.0 70.0 76.0 70.0 70.0 20:42:24 INFO - PROCESS | 27851 | -------- Summary: end -------- 20:42:24 INFO - PROCESS | 27851 | 20:42:25 INFO - TEST-INFO | 27851: exit 0 20:42:25 INFO - TEST-OK | kraken | took 64245ms 20:42:25 INFO - SUITE-END | took 1183s 20:42:25 INFO - Completed test suite (00:19:43) 20:42:25 INFO - javascript benchmark 20:42:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6264.847634085492, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2523.2682522068603, 2541.75853293937, 2543.923682289531, 2556.2714626331476, 2564.5383830910428, 4362.389265502832, 4464.330350447434, 4509.098180363928, 4563.931541026883, 4621.098885717278, 4420.22646036943, 4501.369808226851, 4560.589546940775, 4570.223062079446, 4599.402077729899, 4188.81150348235, 4246.808893599796, 4253.936190957138, 4269.3595960606, 4296.205202037633, 4285.185814695214, 4303.935440968388, 4304.053108316181, 4322.567743225683, 4368.89077773055, 4032.233875563663, 4037.596240375968, 4098.815553300079, 4227.957720422787, 4257.936130958038, 4143.854965076238, 4160.83356665733, 4181.435506206653, 4205.432266643995, 4217.40956266123, 4132.33882578786, 4144.295469770146, 4167.333226683746, 4184.7070705050655, 4192.664586833076, 4426.955730442686, 4449.087936972913, 4468.709533880287, 4517.277235642281, 4521.344405061281, 4820.39745031871, 4847.5637192652475, 4857.101436234308, 4882.267659851015, 4934.506549345073, 4890.437599676012, 4895.657303988723, 4897.094037603055, 4907.092187945242, 4939.8024079036795, 4391.429114215131, 4590.150822097922, 4600.148972440109, 4601.102784956968, 4636.8145274188655, 4802.73584952824, 4857.781399837016, 4865.489123642037, 4866.343043689128, 4877.5366340197625, 4666.206744853315, 4686.718796872198, 4746.810127594927, 4781.402324709411, 4784.808607655655, 4873.6832105913, 4878.463369029404, 4885.071836351153, 4927.310176575283, 4941.407031156285, 4825.662203645711, 4865.0, 4880.731559764247, 4882.292067650171, 4922.064807686528, 4663.673542851968, 4712.787924543404, 4744.572988431058, 4781.497942715965, 4880.341153944243, 4134.73124246923, 4139.441175441337, 4143.606357396043, 4172.415861779354, 4172.83308667656, 3595.5865075516126, 3599.0642432967616, 3604.098975256186, 3631.1829838286703, 3637.563492380931, 4066.0, 4107.9178416432105, 4193.601607847249, 4230.323148296257, 4241.830326786894, 4826.975865120652, 4844.224924012141, 4873.902521949542, 4899.559039686445, 4905.70565766048, 4217.725847819882, 4223.598758117974, 4224.55642157569, 4268.807903644343, 4284.700070995001, 4298.3552467130185, 4323.740575565476, 4387.605115539617, 4409.250427427345, 4431.601155895984, 4519.367288579667, 4524.140413321458, 4531.864044078683, 4535.8185672574045, 4536.183486972377, 4799.3760811095735, 4829.782659780248, 4836.95163048365, 4855.053264613366, 4856.68431551948, 4488.5960263575125, 4504.774761261859, 4529.275315949432, 4541.386912766734, 4589.151007063704, 4754.619630429626, 4760.09558183944, 4764.880877978078, 4768.57082862544, 4814.542618451242, 4798.0, 4824.372831531879, 4826.300186472871, 4835.8065677372515, 4866.7079975201605, 4578.359029735773, 4618.284165954283, 4637.049404871926, 4645.5586719262965, 4659.930101048555, 4632.976835115803, 4661.743604101672, 4665.4634717007975, 4702.7883745230865, 4735.076660051257, 4590.862274131781, 4596.287575425815, 4639.396878405786, 4644.117617652635, 4688.765561721969, 4603.907921841612, 4650.4186976627925, 4715.905681886275, 4730.834420795256, 4732.384789977281, 4644.512326205835, 4661.207594708975, 4673.088747694167, 4693.647976401715, 4695.647826413101], "value": 4449.627271515872, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.8587924200087, 539.9487048730371, 552.6794459213656, 573.1317054662185, 592.8221533539937, 38493.23013539731, 40556.39165412521, 40798.59201407985, 43370.0, 43462.565374346246, 41335.45310015904, 41840.56072710551, 41886.79056604713, 41903.79048104755, 41962.0, 7595.544267343949, 7744.148143704188, 7753.418493612973, 7760.7283745068935, 7764.0, 9322.300827437935, 9575.281853860954, 9591.088846559565, 9772.511374431286, 9826.0, 9528.0, 9627.229821614254, 9655.806883862319, 9689.467079310634, 9695.272854535902, 9602.231821454301, 9606.039396060409, 9628.3260171788, 9635.181009614227, 9656.082672146205, 9453.054694530561, 9459.66891158813, 9485.0, 9519.190868776162, 9540.093691099335, 34087.97736067922, 34149.0, 34297.48553771695, 34362.6563734362, 34406.0, 3313.2710803623163, 3313.4698448248164, 3374.5275661407422, 3380.222548813762, 3403.1322012886803, 24960.251192464257, 25437.491250175077, 25486.36284092894, 25535.48929021428, 25559.0, 3390.491426286052, 3514.736394770377, 3520.5951315598777, 3658.140337020798, 3701.1117331840433, 233638.0, 233840.83079584665, 234436.0, 234600.0, 235388.0, 39563.999999999425, 40914.18171636551, 46375.579907214684, 47029.5297047032, 47075.5292447078, 60084.69957650184, 60856.08715869265, 61387.0, 61706.69146654238, 67193.66403167952, 13413.262270575213, 13466.663333416744, 13521.0, 13629.18224906489, 13725.656858578415, 9577.042295770507, 9596.23230141587, 9619.470929098827, 9626.518674066408, 9634.951825240829, 18886.81113188878, 19127.713084303745, 19160.425187244404, 19165.137568809438, 19219.32732354361, 6969.6515174241085, 6978.266627354341, 6996.790096297119, 7090.865461526131, 7302.634868256566, 716.8996340512228, 727.4326025699962, 731.4623751542612, 735.797655644702, 741.6884908338511, 26748.331291717863, 27892.30269243285, 28141.155765327072, 28400.85799570989, 28625.57061644077, 857.1300130367682, 864.8789169516273, 869.7043005378201, 876.2551830943647, 899.3075331994328, 14316.642083947985, 14566.271686415637, 14757.188354640597, 15017.24913754308, 15043.398264069534, 339283.0, 345247.0, 348073.0, 348899.0, 349313.0, 35655.4651680225, 35740.28519429597, 35795.10512237163, 35972.460413093824, 36039.0, 1104.276698762312, 1154.9076073914066, 1158.8956993870424, 1159.4608507044265, 1170.4908364861312, 25263.242102736946, 26249.475010500064, 26783.54686446408, 27003.59494607543, 27348.179554613394, 42252.15495690008, 42647.57352426436, 42725.718228453195, 42744.14511709811, 43247.35128973131, 277655.0, 278817.0, 279525.0, 279661.0, 279842.0, 29569.556456653598, 30525.847370763004, 35741.642583573834, 37166.814165929, 38205.23589528138, 40137.59862401456, 40483.0, 40630.39054414245, 41649.79175104105, 41693.37459938164, 35124.297514050086, 35282.11794705153, 35509.467357990165, 35726.821365894044, 35778.463323049655, 34442.311153777286, 34532.65467345295, 34546.82726586351, 34647.30705385929, 35006.47490287699, 33459.49810752792, 33683.326333473684, 33950.66049339475, 34108.82945585256, 34455.827720861234, 28650.853965841132, 31399.372012560078, 31436.685633143377, 31452.0, 31557.52663710092, 23561.29316120519, 23784.524309514058, 23798.286051418487, 24017.51964960726, 24156.154534591205], "value": 17313.771388001525, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.4217087624878, 389.9434581985612, 390.4728616367903, 397.65006794021264, 422.3453646847388, 16833.747493787603, 17816.287348506077, 18398.54003649908, 18439.170237339302, 18668.53328666784, 16983.660326793488, 17052.23264953077, 17146.914265428688, 17282.740758888594, 17335.739963900553, 5042.067217564744, 5072.822451214208, 5253.527182553569, 5267.55225805807, 5284.048871203182, 5117.232415137741, 5235.450277720842, 5238.319018527586, 5245.763940622681, 5247.580193584504, 5162.922556161642, 5174.844754657366, 5195.636305458619, 5201.609879259052, 5208.895822083556, 5001.974595207992, 5088.643794934356, 5108.055009823176, 5131.307273518065, 5135.922961155586, 4562.543745625444, 4566.88582785433, 4567.1550763108935, 4569.977150114228, 4572.222722137245, 13230.536931207362, 16149.838501614951, 20933.89533052325, 21766.673499897515, 21905.890470547547, 2101.8423618228694, 2161.0923412166926, 2193.5064610462678, 2218.744844342889, 2222.411061068818, 9726.610935562569, 9901.257405694601, 10094.141997930185, 11953.701157471045, 13414.798778018338, 1612.0730579916478, 1635.803703555581, 1650.3976048742243, 1664.675388299282, 1726.8359505846925, 72282.2771772286, 75550.62224688896, 75563.24436755672, 77537.22462775413, 78592.0, 10480.947595262127, 10928.945355273332, 10934.0, 10967.396793176453, 11058.612948546763, 15539.689206216039, 15557.766633500507, 15574.299156537983, 15578.454754083552, 15584.92207538955, 6740.730370785212, 6985.0, 7013.859722805515, 7193.964030179815, 7248.275172482709, 6262.655553944577, 6311.147995020705, 6459.128017717548, 6476.4495017924, 6504.642244676588, 4596.4254468191475, 4641.489436162019, 4654.604358629555, 4731.834385796481, 4755.9286610700865, 1961.3331467301186, 1963.7741659709316, 1975.1309423853459, 1986.2750096214777, 2020.3433883987764, 730.4521608793405, 749.8350362920149, 754.6340025087828, 760.5322726523149, 763.8319569694659, 17376.304947801946, 17639.559011024827, 18018.729719054223, 18235.17941692627, 18599.628007439776, 751.7631945937012, 753.9396848252236, 764.3846703403814, 764.9388048956071, 783.7374479549301, 9538.427694338361, 9988.900110998942, 10080.848787268196, 10095.7476063099, 10155.187584993182, 473565.0, 481095.0, 481296.0, 493305.0, 493395.0, 61029.38970610326, 64250.67874660597, 84081.15918840855, 84610.57694711487, 85669.0, 925.833349997007, 929.2287401456774, 966.367029595616, 968.3608818179968, 969.3263182088381, 15070.924645376703, 15341.232938353261, 15777.842221577637, 15932.645725113032, 15989.280482378088, 38346.233075338896, 38704.80647596744, 38714.6128538711, 39120.608793912834, 39412.60587394089, 50921.49078509167, 51009.0, 51299.0, 51792.741036294574, 52325.0, 7498.687729647399, 7743.4966727162555, 7921.366290696616, 8039.236272554098, 8449.408541402043, 9212.124848139416, 11830.231034982698, 12263.632091037283, 12714.173578717353, 12821.102522823312, 9228.723138305862, 9691.660791872251, 9949.0, 10241.436720980417, 10310.0, 10129.64546240878, 11013.449327533752, 11132.443377831238, 11198.328100314007, 11354.659360219206, 9405.388649737744, 10296.433696146783, 10596.788064238517, 10923.12614990783, 11035.841236670034, 7906.841863162589, 7971.883936248813, 8015.679372825255, 8181.918180818354, 8410.747677569683, 8242.17578242171, 10376.273660843668, 10398.74003149897, 10486.79026419452, 10633.840492392776], "value": 8358.094095987897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.9733405331895, 1347.6294019144734, 1353.546561901763, 1354.1942544186218, 1361.448613311609, 7221.350078492935, 7389.556626602408, 7547.584882831442, 7558.093028836541, 7583.6966521339145, 7687.807804804883, 7776.611169441519, 7777.4555781095205, 7787.571683557402, 7788.532688038722, 2036.7454068241432, 2075.885826279554, 2078.3557097299886, 2080.3238947342097, 2084.5101401170787, 2052.1175894365415, 2091.592139532792, 2096.0986775686447, 2100.9159633614636, 2105.0737675422843, 2085.937421877346, 2097.475631092227, 2118.67160590108, 2118.7033815265877, 2119.4171602809197, 2038.8878611676353, 2052.917883284667, 2062.679884873682, 2063.308791554831, 2070.6997485364614, 1743.3549586653019, 1786.1872847854195, 2015.7782643909159, 2055.4553043443498, 2111.345482900306, 7224.602646854473, 7438.479306448551, 7463.477556571042, 7578.924210757877, 7586.620668966585, 4389.1660584488845, 4523.0, 4524.3213517972235, 4529.365888775574, 4534.6825722199455, 2798.552231642948, 2815.2398852309966, 2853.6432945881766, 2856.1717102040384, 2886.8123571967753, 1064.914806815453, 1070.6841481762822, 1111.1610733895939, 1119.1997721628993, 1132.5356603792488, 10153.898461015444, 10239.436830974293, 10276.845847312221, 10277.537510811957, 10303.381797092123, 3712.6287371262656, 3825.8660946866726, 3926.0, 3929.901752456154, 3957.4855268815445, 8146.796330091676, 8226.136255693065, 8235.917640823634, 8236.299914507214, 8251.83496330082, 4480.081583275421, 4505.16654418927, 4509.842155524541, 4516.119356725407, 4519.05099929012, 4147.52303485097, 4155.397467367276, 4217.683673724483, 4241.7879106044575, 4266.807993640294, 3235.514672799052, 3281.786683865541, 3303.1907182740383, 3313.867445302161, 3397.558317418769, 994.8109859126743, 1001.8497225416246, 1010.4796030044533, 1015.746063484129, 1023.8822535408524, 782.4444644302608, 812.1269635142246, 812.5896422306815, 820.5609998650813, 821.880827280041, 4664.626829853603, 4689.742064186435, 4844.394450693663, 4874.561289483963, 5052.1663925451885, 489.81876705618436, 491.34160225298274, 526.3552148617989, 528.7409169506914, 538.9649672771258, 3250.7724459287624, 3295.6869097435706, 3303.9834800825843, 3310.9503357449657, 3390.983045084808, 7960.925275087905, 8261.463004904663, 8585.871211931826, 8837.160469755492, 8897.777555561033, 9076.364654474124, 9138.177564019137, 9154.0, 9157.77105572366, 9177.678781242625, 6443.0, 6620.106285651472, 6745.460363170934, 6749.156355455568, 6752.189737231563, 4938.555530002317, 4942.752862356796, 4964.106460837084, 4997.350344455198, 5046.34397528319, 7625.275598818103, 7762.0, 7860.882086768589, 7874.251946065115, 7929.246721561442, 7987.361011119213, 8064.112947575879, 8133.186681332056, 8156.755297341089, 8166.795830104057, 5762.049261871744, 5888.94111058901, 5900.911486327623, 5905.025670764448, 5906.911396328973, 6817.7272909083085, 6831.555948863308, 6884.621345825827, 6896.27589103137, 6932.792016239521, 6231.1276421300145, 6235.127082208585, 6260.780872669435, 6295.150154729053, 6311.116443697978, 6170.167027451236, 6241.875162496815, 6254.874902502015, 6299.4645455135, 6300.527460440577, 5604.523615492566, 5696.601237913472, 5735.770569177186, 5760.279965004374, 5813.6221145625395, 4448.510663827041, 4468.530804265635, 4558.498565157764, 4635.860924172281, 4719.669623126349, 4127.525334586561, 4541.68208225421, 4588.632909367309, 4626.907461850811, 4748.0978614063215], "value": 3855.793258176639, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.6440962423143, 357.77638975640224, 365.7366695978895, 367.4653379333069, 368.96310368963105, 4897.559219670229, 4925.1627223372025, 4968.527989840959, 4972.751362431877, 5019.0, 4874.269634279824, 4953.975230123844, 4959.305697202394, 4962.801487940478, 4971.37857767779, 3705.888823335291, 3867.994321476422, 3872.3223435898744, 3892.9221415571674, 3909.1009067914397, 3897.395903634927, 3899.6880249579967, 3909.491766070407, 3916.138449541096, 3917.6278253566006, 3860.343741563941, 3869.5163104611925, 3872.6514613684903, 3873.4964454621, 3873.922521549567, 3669.853205871762, 3882.650561449469, 3884.7086468514976, 3885.358915778893, 3902.1415288636595, 3391.1861153323025, 3394.62659107498, 3415.9146021349416, 3416.265502916885, 3417.863285468603, 4909.538775163501, 4909.582685471739, 4917.467504235322, 4919.163742163805, 4945.0, 4420.734755914656, 4426.15902978433, 4426.490953540352, 4435.911281774346, 4442.378067070613, 4958.305837182797, 5202.297689811865, 5214.113600687892, 5230.829205879555, 5240.929832716056, 3679.356112680297, 3705.1292946157632, 3713.4589145504583, 3717.5910649828497, 3725.5343082114737, 5241.580673546107, 5444.727763611843, 5466.508014278734, 5469.507744303032, 5494.066008778477, 3738.6074462181623, 3767.868124615625, 3774.3206222880144, 3775.942736033915, 3783.1487915219404, 3807.314683357022, 3820.2932457495453, 3827.3876179811095, 3852.325842977468, 3870.8838734838, 4205.495340559091, 4396.450443694538, 4420.491643460979, 4422.5356337584735, 4429.269170586882, 5192.92210616841, 5227.503387178212, 5235.973820130875, 5244.868878278074, 5262.184361423985, 1287.0668765145383, 1293.049608537724, 1296.6434230586476, 1297.4874924404808, 1310.9082364234603, 820.782492639451, 881.713443130985, 884.35883984111, 898.0929261445987, 910.5674804467825, 4441.511433742286, 4455.732656040648, 4473.955260447419, 4474.351219073281, 4478.39541661878, 5271.077561426811, 5275.841724748264, 5309.946900531023, 5309.973450132802, 5325.707086110224, 4296.720713153698, 4453.309736990772, 4456.309272062771, 4499.77501124949, 4543.363929049936, 2759.1722483255344, 2781.95676621267, 2787.874545645451, 2796.231036464948, 2797.342624483245, 4892.608591312686, 4902.730349830722, 4928.01439712059, 4928.669259299969, 4929.630277729113, 4993.550580447777, 5276.287701160371, 5321.840344789663, 5341.706206158621, 5367.624266301399, 4873.658843880894, 4882.267659851051, 4890.967600087968, 4909.754512274372, 4910.0, 5209.47905209476, 5218.452062533379, 5292.788288468418, 5303.708296043755, 5313.335833020872, 4867.5132486751045, 5103.413107492686, 5141.2288156776785, 5214.556762675215, 5215.687058776485, 4798.904021919612, 4877.244027175793, 4881.8535443936735, 4885.755712214446, 4913.606911447004, 3775.8300876461176, 3782.508273924372, 3790.9052273692273, 3790.962090379061, 3806.238752249506, 5347.51872331492, 5353.0, 5388.0, 5391.2182733504205, 5402.108652072364, 4925.0, 5164.509371609691, 5201.401838788588, 5216.165413533817, 5223.973880130727, 5160.561352285098, 5203.713795741271, 5223.399309079505, 5250.159974404077, 5270.420253772006, 5158.200478925773, 5179.782751053574, 5182.948170518397, 5204.531592156724, 5221.399539053057, 4975.950240497548, 5249.501297376743, 5249.737513124405, 5291.629585929102, 5294.576433885203, 5132.9743351283005, 5138.122651315275, 5139.254808052737, 5142.665726727901, 5153.665011774223], "value": 3913.4278558969622, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.9967900160499, 649.8960166373381, 714.2393351081098, 723.4863247094563, 737.9003834482346, 11779.941100294498, 12869.0, 12890.935545322283, 12903.612891613244, 12905.096643234978, 11827.0, 11901.166918315721, 11917.702057448547, 11918.940405297983, 11924.642260732171, 769.6575024114271, 788.597815114293, 795.2286282306154, 800.4436916343144, 803.8472690188875, 811.8295158016823, 816.9468984516017, 818.2349503214484, 818.5907046476761, 818.8362327534487, 849.6771226933787, 851.1403482482706, 851.974440766778, 853.9530325832077, 854.4189950833431, 842.7640260727006, 845.6321500147425, 847.5338563789921, 848.1052489623445, 850.1880703927734, 618.7339444039061, 620.3982137326785, 622.2377587455376, 622.3745136138198, 623.3703959001396, 12235.0, 12690.11169218155, 12775.680607984781, 12849.421776020008, 12948.870511294861, 12269.300753834143, 12564.685882853002, 12627.0, 12671.239725616493, 12827.807582886264, 10207.234457415649, 10209.693709188661, 10229.283950123494, 10256.128229100535, 10765.30025548337, 3118.313970926415, 3126.265327648001, 3251.8536665850093, 3281.196106953788, 3287.7534184935985, 62060.0, 62204.68897655528, 62794.37205627931, 63731.681341592994, 65060.3493965059, 8824.691135810215, 8983.416077954913, 9167.22078623311, 9182.540872956326, 9184.954075229582, 18429.5392615183, 18592.81407185911, 18690.81309186918, 18713.90643046776, 18826.529336766416, 5964.284285885635, 6007.969960150172, 6091.269047714302, 6240.968795155995, 6371.362863713684, 3268.150280926976, 3341.4152523308326, 3350.0, 3427.1603457153155, 3462.774919630216, 2804.775617950559, 2918.7956843020784, 2958.7485063769823, 2972.806767560145, 2980.7466365359187, 578.3233616668508, 580.9419058094242, 586.8415527807468, 595.7259660556193, 597.9521638268928, 572.4390097704273, 586.5835256967515, 589.5548860610212, 599.4814485470037, 609.4697613076652, 8057.6374063167295, 8086.191380861985, 8159.306458951056, 8436.36727245459, 9029.322800789967, 609.7987664070846, 630.4357599948113, 632.7152781248457, 641.3746597067822, 652.4095693397483, 3835.4438606401914, 4498.977505112453, 4704.15325241453, 4706.599939005155, 4760.64295177863, 63722.6813865937, 66144.66927665331, 66643.66954130733, 69351.3064869355, 70138.64930675416, 56063.43936560664, 56549.0, 57369.42630573641, 60109.0, 61686.0, 50884.745576272624, 51110.74444627753, 51260.487395126314, 51470.22794658083, 51842.22236666453, 8703.434276772243, 9276.72169834906, 9657.565409556446, 9665.0, 9672.758181045248, 31627.0, 32637.510437343935, 32956.1760955978, 33957.49063763996, 33960.66039339575, 43027.139457211306, 44934.775326124465, 45175.77412112919, 45565.0, 46208.30687539757, 10261.486925653837, 10296.485175741333, 10307.536160872629, 10384.0, 10389.896101039196, 12243.510259589517, 12621.179623324455, 12809.334786478072, 12835.807462888251, 13402.664933376744, 10972.286801357886, 11350.602728904449, 11359.148063895405, 11454.713632159262, 11514.251573647685, 10963.671089867317, 11388.829167562659, 11407.543698251977, 11476.196666233283, 11492.885071149181, 10605.522751476356, 11190.216684832309, 11237.887621123684, 11284.743884726771, 11311.377874216998, 8779.912200878165, 8880.424377791918, 9093.272538197061, 9223.677171298972, 9383.249340052917, 8471.057884231537, 8941.418807777734, 9023.401556787996, 9448.149666530046, 9601.183899368632], "value": 6221.818393432588, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 115.0, 115.0, 115.0, 115.0, 116.0, 114.0, 116.0, 116.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 127.0, 127.0, 182.0, 129.0, 132.0, 129.0, 135.0, 151.0, 129.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 169.0, 166.0, 173.0, 163.0, 163.0, 170.0, 164.0, 165.0], "value": 165.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 79.0, 77.0, 113.0, 78.0, 78.0, 78.0, 78.0, 99.0, 77.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 103.0, 86.0, 88.0, 90.0, 95.0, 88.0, 86.0, 108.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 116.0, 114.0, 114.0, 119.0, 114.0, 115.0, 115.0, 115.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 151.0, 152.0, 150.0, 152.0, 152.0, 154.0, 150.0, 150.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 98.0, 96.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 66.0, 66.0, 67.0, 67.0, 76.0, 67.0, 66.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 67.0, 65.0, 65.0, 66.0, 65.0, 66.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 90.0, 92.0, 88.0, 88.0, 88.0, 87.0, 87.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 139.0, 145.0, 154.0, 143.0, 151.0, 144.0, 141.0, 185.0, 171.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 196.0, 181.0, 187.0, 187.0, 187.0, 183.0, 190.0, 182.0, 195.0], "value": 187.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 76.0, 74.0, 72.0, 70.0, 70.0, 76.0, 70.0, 70.0], "value": 72.1, "unit": "score"}]}]} 20:42:25 INFO - Return code: 0 20:42:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:42:25 ERROR - # TBPL SUCCESS # 20:42:25 INFO - Running post-action listener: _resource_record_post_action 20:42:25 INFO - [mozharness: 2017-02-23 04:42:25.974596Z] Finished run-tests step (success) 20:42:25 INFO - Running post-run listener: _resource_record_post_run 20:42:26 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 349577216; Read time: 12; Write time: 205552 20:42:26 INFO - TinderboxPrint: CPU usage
13.5% 20:42:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 12 20:42:26 INFO - TinderboxPrint: I/O write bytes / time
349,577,216 / 205,552 20:42:26 INFO - TinderboxPrint: CPU idle
8,275.3 (86.5%) 20:42:26 INFO - TinderboxPrint: CPU user
1,235.4 (12.9%) 20:42:26 INFO - TinderboxPrint: Swap in / out
0 / 0 20:42:26 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105156608; Read time: 0; Write time: 74044 20:42:26 INFO - run-tests - Wall time: 1184s; CPU: 14.0%; Read bytes: 8192; Write bytes: 244420608; Read time: 12; Write time: 131508 20:42:26 INFO - Running post-run listener: _upload_blobber_files 20:42:26 INFO - Blob upload gear active. 20:42:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:42:26 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 20:42:26 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'] 20:42:26 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 20:42:27 INFO - (blobuploader) - INFO - Open directory for files ... 20:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:42:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:27 INFO - (blobuploader) - INFO - Done attempting. 20:42:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:42:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:42:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:28 INFO - (blobuploader) - INFO - Done attempting. 20:42:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:42:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:42:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:32 INFO - (blobuploader) - INFO - Done attempting. 20:42:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:42:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:42:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:42:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:42:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:42:33 INFO - (blobuploader) - INFO - Done attempting. 20:42:33 INFO - (blobuploader) - INFO - Iteration through files over. 20:42:33 INFO - Return code: 0 20:42:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:42:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:42:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/01d45f582af2a378626187d7addff5a4d5da36a07a146816d35c6b2434be99197bf75f5bb52fc9ad5bbd448ee4fd4ef8bb2bb20f92ceb61aceef3e5c106ea8b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fcdba30a740f7de884d293cf84ecd9679de58c2239a7c4191e8f4637f895abe92cdbabe5efc2ea8a8f2ee386de4ee0933291fb96f2b52a3b5648dfeb44519e90", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf8ac5857bde09d42dacaea79ee6f4e4e41f4af3c56206d05b514881e44c5acda5decf6a0b11f6a8fa2b42077cbb2d5b3748c38bd1ec4ac35eb4d3509e1d0dae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/435ae1896471bb9351b939722da7fb84a9b3ea709b67492458fae4c91c92455eead5908611e5c7c68d7e89596e19ac7e519413b9096ec2b1df0a155be8d561db"} 20:42:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:42:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:42:33 INFO - Contents: 20:42:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/01d45f582af2a378626187d7addff5a4d5da36a07a146816d35c6b2434be99197bf75f5bb52fc9ad5bbd448ee4fd4ef8bb2bb20f92ceb61aceef3e5c106ea8b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fcdba30a740f7de884d293cf84ecd9679de58c2239a7c4191e8f4637f895abe92cdbabe5efc2ea8a8f2ee386de4ee0933291fb96f2b52a3b5648dfeb44519e90", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf8ac5857bde09d42dacaea79ee6f4e4e41f4af3c56206d05b514881e44c5acda5decf6a0b11f6a8fa2b42077cbb2d5b3748c38bd1ec4ac35eb4d3509e1d0dae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/435ae1896471bb9351b939722da7fb84a9b3ea709b67492458fae4c91c92455eead5908611e5c7c68d7e89596e19ac7e519413b9096ec2b1df0a155be8d561db"} 20:42:33 INFO - Running post-run listener: copy_logs_to_upload_dir 20:42:33 INFO - Copying logs to upload dir... 20:42:33 INFO - mkdir: /builds/slave/test/build/upload/logs 20:42:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.284129 ========= master_lag: 0.58 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-22 20:42:33.986148) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:33.997777) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/01d45f582af2a378626187d7addff5a4d5da36a07a146816d35c6b2434be99197bf75f5bb52fc9ad5bbd448ee4fd4ef8bb2bb20f92ceb61aceef3e5c106ea8b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fcdba30a740f7de884d293cf84ecd9679de58c2239a7c4191e8f4637f895abe92cdbabe5efc2ea8a8f2ee386de4ee0933291fb96f2b52a3b5648dfeb44519e90", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf8ac5857bde09d42dacaea79ee6f4e4e41f4af3c56206d05b514881e44c5acda5decf6a0b11f6a8fa2b42077cbb2d5b3748c38bd1ec4ac35eb4d3509e1d0dae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/435ae1896471bb9351b939722da7fb84a9b3ea709b67492458fae4c91c92455eead5908611e5c7c68d7e89596e19ac7e519413b9096ec2b1df0a155be8d561db"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011196 build_url: 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/01d45f582af2a378626187d7addff5a4d5da36a07a146816d35c6b2434be99197bf75f5bb52fc9ad5bbd448ee4fd4ef8bb2bb20f92ceb61aceef3e5c106ea8b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fcdba30a740f7de884d293cf84ecd9679de58c2239a7c4191e8f4637f895abe92cdbabe5efc2ea8a8f2ee386de4ee0933291fb96f2b52a3b5648dfeb44519e90", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf8ac5857bde09d42dacaea79ee6f4e4e41f4af3c56206d05b514881e44c5acda5decf6a0b11f6a8fa2b42077cbb2d5b3748c38bd1ec4ac35eb4d3509e1d0dae", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/435ae1896471bb9351b939722da7fb84a9b3ea709b67492458fae4c91c92455eead5908611e5c7c68d7e89596e19ac7e519413b9096ec2b1df0a155be8d561db"}' ========= master_lag: 0.32 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:34.328036) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:34.328469) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1487823564.712286-163442668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 20:42:34.371960) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:34.372366) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 20:42:34.378996) ========= ========= Total master_lag: 1.02 =========